builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1472897826.85 results: success (0) buildid: 20160902160050 builduid: b954e943d60047298c8c718863c8a0b0 revision: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:06.851621) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:06.852073) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:06.852342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:06.866953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:06.867272) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-03 03:17:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 175M=0s 2016-09-03 03:17:09 (175 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122685 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.011145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.011452) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020091 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.061582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.061965) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-03 03:17:09,420 truncating revision to first 12 chars 2016-09-03 03:17:09,420 Setting DEBUG logging. 2016-09-03 03:17:09,420 attempt 1/10 2016-09-03 03:17:09,420 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d35ac86c5271?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 03:17:09,727 unpacking tar archive at: mozilla-aurora-d35ac86c5271/testing/mozharness/ program finished with exit code 0 elapsedTime=0.617788 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.693276) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.693594) ========= script_repo_revision: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.694002) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.694260) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 03:17:07.702375) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-03 03:17:07.702648) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:17:10 INFO - MultiFileLogger online at 20160903 03:17:10 in /builds/slave/test 03:17:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:17:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:17:10 INFO - {'append_to_log': False, 03:17:10 INFO - 'base_work_dir': '/builds/slave/test', 03:17:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:17:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:17:10 INFO - 'branch': 'Mozilla-Aurora', 03:17:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:17:10 INFO - 'config_files': ('talos/linux_config.py',), 03:17:10 INFO - 'default_actions': ('clobber', 03:17:10 INFO - 'read-buildbot-config', 03:17:10 INFO - 'download-and-extract', 03:17:10 INFO - 'populate-webroot', 03:17:10 INFO - 'create-virtualenv', 03:17:10 INFO - 'install', 03:17:10 INFO - 'run-tests'), 03:17:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:17:10 INFO - 'download_minidump_stackwalk': True, 03:17:10 INFO - 'download_symbols': 'ondemand', 03:17:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:17:10 INFO - 'tooltool.py': '/tools/tooltool.py', 03:17:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:17:10 INFO - '/tools/misc-python/virtualenv.py')}, 03:17:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:17:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:17:10 INFO - 'installer_path': 'installer.exe', 03:17:10 INFO - 'log_level': 'info', 03:17:10 INFO - 'log_name': 'talos', 03:17:10 INFO - 'log_to_console': True, 03:17:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:17:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:17:10 INFO - 'opt_config_files': (), 03:17:10 INFO - 'pip_index': False, 03:17:10 INFO - 'sps_profile': False, 03:17:10 INFO - 'sps_profile_interval': 0, 03:17:10 INFO - 'suite': 'dromaeojs', 03:17:10 INFO - 'system_bits': '32', 03:17:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:17:10 INFO - 'title': 'talos-linux64-ix-059', 03:17:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:17:10 INFO - 'use_talos_json': True, 03:17:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:17:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:17:10 INFO - 'work_dir': 'build'} 03:17:10 INFO - [mozharness: 2016-09-03 10:17:10.154023Z] Running clobber step. 03:17:10 INFO - Running pre-action listener: _resource_record_pre_action 03:17:10 INFO - Running main action method: clobber 03:17:10 INFO - rmtree: /builds/slave/test/build 03:17:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:17:10 INFO - Running post-action listener: _resource_record_post_action 03:17:10 INFO - [mozharness: 2016-09-03 10:17:10.614758Z] Finished clobber step (success) 03:17:10 INFO - [mozharness: 2016-09-03 10:17:10.614859Z] Running read-buildbot-config step. 03:17:10 INFO - Running pre-action listener: _resource_record_pre_action 03:17:10 INFO - Running main action method: read_buildbot_config 03:17:10 INFO - Using buildbot properties: 03:17:10 INFO - { 03:17:10 INFO - "project": "", 03:17:10 INFO - "product": "firefox", 03:17:10 INFO - "script_repo_revision": "production", 03:17:10 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:17:10 INFO - "repository": "", 03:17:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:17:10 INFO - "buildid": "20160902160050", 03:17:10 INFO - "pgo_build": "True", 03:17:10 INFO - "basedir": "/builds/slave/test", 03:17:10 INFO - "buildnumber": 731, 03:17:10 INFO - "slavename": "talos-linux64-ix-059", 03:17:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:17:10 INFO - "platform": "ubuntu64_hw", 03:17:10 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:17:10 INFO - "branch": "mozilla-aurora", 03:17:10 INFO - "repo_path": "releases/mozilla-aurora", 03:17:10 INFO - "revision": "d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd", 03:17:10 INFO - "stage_platform": "linux64-pgo", 03:17:10 INFO - "builduid": "b954e943d60047298c8c718863c8a0b0", 03:17:10 INFO - "slavebuilddir": "test" 03:17:10 INFO - } 03:17:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:17:10 INFO - Running post-action listener: _resource_record_post_action 03:17:10 INFO - [mozharness: 2016-09-03 10:17:10.617310Z] Finished read-buildbot-config step (success) 03:17:10 INFO - [mozharness: 2016-09-03 10:17:10.617400Z] Running download-and-extract step. 03:17:10 INFO - Running pre-action listener: _resource_record_pre_action 03:17:10 INFO - Running main action method: download_and_extract 03:17:10 INFO - mkdir: /builds/slave/test/build/tests 03:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:17:10 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:17:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:17:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:10 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:17:10 INFO - retry: Failed, sleeping 30 seconds before retrying 03:17:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:17:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:40 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:17:40 INFO - retry: Failed, sleeping 60 seconds before retrying 03:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:18:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:40 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:18:40 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:18:40 INFO - Caught exception: HTTP Error 404: Not Found 03:18:40 INFO - Caught exception: HTTP Error 404: Not Found 03:18:40 INFO - Caught exception: HTTP Error 404: Not Found 03:18:40 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:40 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:18:41 INFO - Downloaded 1361 bytes. 03:18:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:41 INFO - Using the following test package requirements: 03:18:41 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:18:41 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:18:41 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:18:41 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'jsshell-linux-x86_64.zip'], 03:18:41 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:18:41 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:18:41 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:18:41 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:18:41 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:18:41 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:18:41 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:18:41 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:18:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:18:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:18:41 INFO - retry: Failed, sleeping 30 seconds before retrying 03:19:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:19:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:19:11 INFO - retry: Failed, sleeping 60 seconds before retrying 03:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:20:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:20:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:20:11 INFO - Caught exception: HTTP Error 404: Not Found 03:20:11 INFO - Caught exception: HTTP Error 404: Not Found 03:20:11 INFO - Caught exception: HTTP Error 404: Not Found 03:20:11 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:11 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:20:12 INFO - Downloaded 21839364 bytes. 03:20:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:20:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:20:13 INFO - Return code: 0 03:20:13 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:20:13 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:20:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:20:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:20:13 INFO - retry: Failed, sleeping 30 seconds before retrying 03:20:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:20:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:20:43 INFO - retry: Failed, sleeping 60 seconds before retrying 03:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:21:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:21:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:21:43 INFO - Caught exception: HTTP Error 404: Not Found 03:21:43 INFO - Caught exception: HTTP Error 404: Not Found 03:21:43 INFO - Caught exception: HTTP Error 404: Not Found 03:21:43 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:43 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:21:44 INFO - Downloaded 21839364 bytes. 03:21:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:21:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:21:45 INFO - Return code: 0 03:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:45 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:21:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:21:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:21:45 INFO - retry: Failed, sleeping 30 seconds before retrying 03:22:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:22:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:22:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:22:15 INFO - retry: Failed, sleeping 60 seconds before retrying 03:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:23:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:23:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:23:15 INFO - Caught exception: HTTP Error 404: Not Found 03:23:15 INFO - Caught exception: HTTP Error 404: Not Found 03:23:15 INFO - Caught exception: HTTP Error 404: Not Found 03:23:15 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:15 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:23:16 INFO - Downloaded 14413740 bytes. 03:23:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:23:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:23:16 INFO - Return code: 0 03:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:23:16 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:23:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:23:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:23:16 INFO - retry: Failed, sleeping 30 seconds before retrying 03:23:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:23:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:23:47 INFO - retry: Failed, sleeping 60 seconds before retrying 03:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:24:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:24:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:24:47 INFO - Caught exception: HTTP Error 404: Not Found 03:24:47 INFO - Caught exception: HTTP Error 404: Not Found 03:24:47 INFO - Caught exception: HTTP Error 404: Not Found 03:24:47 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:47 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:24:48 INFO - Downloaded 63899078 bytes. 03:24:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:48 INFO - mkdir: /builds/slave/test/properties 03:24:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:24:48 INFO - Writing to file /builds/slave/test/properties/build_url 03:24:48 INFO - Contents: 03:24:48 INFO - build_url:https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:49 INFO - Running post-action listener: _resource_record_post_action 03:24:49 INFO - Running post-action listener: set_extra_try_arguments 03:24:49 INFO - [mozharness: 2016-09-03 10:24:49.372607Z] Finished download-and-extract step (success) 03:24:49 INFO - [mozharness: 2016-09-03 10:24:49.372841Z] Running populate-webroot step. 03:24:49 INFO - Running pre-action listener: _resource_record_pre_action 03:24:49 INFO - Running main action method: populate_webroot 03:24:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:24:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:24:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:24:49 INFO - '--tppagecycles', 03:24:49 INFO - '7'], 03:24:49 INFO - 'tests': ['tsvgm']}}, 03:24:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:24:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:24:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:24:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:24:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['damp', 'tps']}, 03:24:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'tests': ['damp', 'tps']}, 03:24:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['dromaeo_dom']}, 03:24:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:24:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['basic_compositor_video']}, 03:24:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:24:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other-e10s': {'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['a11yr', 03:24:49 INFO - 'ts_paint', 03:24:49 INFO - 'tpaint', 03:24:49 INFO - 'sessionrestore', 03:24:49 INFO - 'sessionrestore_no_auto_restore', 03:24:49 INFO - 'tabpaint']}, 03:24:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tsvgx', 03:24:49 INFO - 'tsvgr_opacity', 03:24:49 INFO - 'tart', 03:24:49 INFO - 'tscrollx', 03:24:49 INFO - 'cart']}, 03:24:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:24:49 INFO - 'tsvgr_opacity', 03:24:49 INFO - 'tart', 03:24:49 INFO - 'tscrollx', 03:24:49 INFO - 'cart']}, 03:24:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s'], 03:24:49 INFO - 'tests': ['tp5o']}, 03:24:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'tests': ['tp5o']}, 03:24:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--disable-e10s', 03:24:49 INFO - '--xperf_path', 03:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:49 INFO - 'tests': ['tp5n']}, 03:24:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:49 INFO - 'talos_options': ['--xperf_path', 03:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:49 INFO - 'tests': ['tp5n']}}, 03:24:49 INFO - 'talos.zip': {'path': '', 03:24:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:24:49 INFO - Running post-action listener: _resource_record_post_action 03:24:49 INFO - [mozharness: 2016-09-03 10:24:49.391389Z] Finished populate-webroot step (success) 03:24:49 INFO - [mozharness: 2016-09-03 10:24:49.391478Z] Running create-virtualenv step. 03:24:49 INFO - Running pre-action listener: _resource_record_pre_action 03:24:49 INFO - Running main action method: create_virtualenv 03:24:49 INFO - Creating virtualenv /builds/slave/test/build/venv 03:24:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:24:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:24:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:24:49 INFO - Using real prefix '/usr' 03:24:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:24:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:24:51 INFO - Installing pip.................done. 03:24:51 INFO - Return code: 0 03:24:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:24:51 INFO - Using env: {'DISPLAY': ':0', 03:24:51 INFO - 'HOME': '/home/cltbld', 03:24:51 INFO - 'LANG': 'en_US.UTF-8', 03:24:51 INFO - 'LANGUAGE': 'en_US:en', 03:24:51 INFO - 'LOGNAME': 'cltbld', 03:24:51 INFO - 'MAIL': '/var/mail/cltbld', 03:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:51 INFO - 'MOZ_NO_REMOTE': '1', 03:24:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:51 INFO - 'NO_EM_RESTART': '1', 03:24:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:51 INFO - 'PWD': '/builds/slave/test', 03:24:51 INFO - 'SHELL': '/bin/bash', 03:24:51 INFO - 'SHLVL': '1', 03:24:51 INFO - 'TERM': 'linux', 03:24:51 INFO - 'TMOUT': '86400', 03:24:51 INFO - 'USER': 'cltbld', 03:24:51 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:51 INFO - '_': '/tools/buildbot/bin/python'} 03:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:24:52 INFO - Cleaning up... 03:24:52 INFO - Return code: 0 03:24:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:24:52 INFO - Using env: {'DISPLAY': ':0', 03:24:52 INFO - 'HOME': '/home/cltbld', 03:24:52 INFO - 'LANG': 'en_US.UTF-8', 03:24:52 INFO - 'LANGUAGE': 'en_US:en', 03:24:52 INFO - 'LOGNAME': 'cltbld', 03:24:52 INFO - 'MAIL': '/var/mail/cltbld', 03:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:52 INFO - 'MOZ_NO_REMOTE': '1', 03:24:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:52 INFO - 'NO_EM_RESTART': '1', 03:24:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:52 INFO - 'PWD': '/builds/slave/test', 03:24:52 INFO - 'SHELL': '/bin/bash', 03:24:52 INFO - 'SHLVL': '1', 03:24:52 INFO - 'TERM': 'linux', 03:24:52 INFO - 'TMOUT': '86400', 03:24:52 INFO - 'USER': 'cltbld', 03:24:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:52 INFO - '_': '/tools/buildbot/bin/python'} 03:24:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:52 INFO - Downloading/unpacking psutil>=3.1.1 03:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:24:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:24:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:24:54 INFO - Installing collected packages: psutil 03:24:54 INFO - Running setup.py install for psutil 03:24:54 INFO - building 'psutil._psutil_linux' extension 03:24:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:24:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:24:54 INFO - building 'psutil._psutil_posix' extension 03:24:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:24:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:24:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:24:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:24:54 INFO - Successfully installed psutil 03:24:54 INFO - Cleaning up... 03:24:54 INFO - Return code: 0 03:24:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:24:54 INFO - Using env: {'DISPLAY': ':0', 03:24:54 INFO - 'HOME': '/home/cltbld', 03:24:54 INFO - 'LANG': 'en_US.UTF-8', 03:24:54 INFO - 'LANGUAGE': 'en_US:en', 03:24:54 INFO - 'LOGNAME': 'cltbld', 03:24:54 INFO - 'MAIL': '/var/mail/cltbld', 03:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:54 INFO - 'MOZ_NO_REMOTE': '1', 03:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:54 INFO - 'NO_EM_RESTART': '1', 03:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:54 INFO - 'PWD': '/builds/slave/test', 03:24:54 INFO - 'SHELL': '/bin/bash', 03:24:54 INFO - 'SHLVL': '1', 03:24:54 INFO - 'TERM': 'linux', 03:24:54 INFO - 'TMOUT': '86400', 03:24:54 INFO - 'USER': 'cltbld', 03:24:54 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:54 INFO - '_': '/tools/buildbot/bin/python'} 03:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:24:57 INFO - Installing collected packages: mozsystemmonitor 03:24:57 INFO - Running setup.py install for mozsystemmonitor 03:24:57 INFO - Successfully installed mozsystemmonitor 03:24:57 INFO - Cleaning up... 03:24:57 INFO - Return code: 0 03:24:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:24:57 INFO - Using env: {'DISPLAY': ':0', 03:24:57 INFO - 'HOME': '/home/cltbld', 03:24:57 INFO - 'LANG': 'en_US.UTF-8', 03:24:57 INFO - 'LANGUAGE': 'en_US:en', 03:24:57 INFO - 'LOGNAME': 'cltbld', 03:24:57 INFO - 'MAIL': '/var/mail/cltbld', 03:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:57 INFO - 'MOZ_NO_REMOTE': '1', 03:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:57 INFO - 'NO_EM_RESTART': '1', 03:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:57 INFO - 'PWD': '/builds/slave/test', 03:24:57 INFO - 'SHELL': '/bin/bash', 03:24:57 INFO - 'SHLVL': '1', 03:24:57 INFO - 'TERM': 'linux', 03:24:57 INFO - 'TMOUT': '86400', 03:24:57 INFO - 'USER': 'cltbld', 03:24:57 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:57 INFO - '_': '/tools/buildbot/bin/python'} 03:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:57 INFO - Downloading/unpacking jsonschema==2.5.1 03:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:24:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:24:59 INFO - warning: no files found matching '*.txt' 03:24:59 INFO - no previously-included directories found matching 'build' 03:24:59 INFO - no previously-included directories found matching 'dist' 03:24:59 INFO - no previously-included directories found matching '.git*' 03:24:59 INFO - Installing collected packages: jsonschema, functools32 03:24:59 INFO - Running setup.py install for functools32 03:24:59 INFO - warning: no files found matching '*.txt' 03:24:59 INFO - no previously-included directories found matching 'build' 03:24:59 INFO - no previously-included directories found matching 'dist' 03:24:59 INFO - no previously-included directories found matching '.git*' 03:24:59 INFO - Successfully installed jsonschema functools32 03:24:59 INFO - Cleaning up... 03:24:59 INFO - Return code: 0 03:24:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:24:59 INFO - Using env: {'DISPLAY': ':0', 03:24:59 INFO - 'HOME': '/home/cltbld', 03:24:59 INFO - 'LANG': 'en_US.UTF-8', 03:24:59 INFO - 'LANGUAGE': 'en_US:en', 03:24:59 INFO - 'LOGNAME': 'cltbld', 03:24:59 INFO - 'MAIL': '/var/mail/cltbld', 03:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:59 INFO - 'MOZ_NO_REMOTE': '1', 03:24:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:59 INFO - 'NO_EM_RESTART': '1', 03:24:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:59 INFO - 'PWD': '/builds/slave/test', 03:24:59 INFO - 'SHELL': '/bin/bash', 03:24:59 INFO - 'SHLVL': '1', 03:24:59 INFO - 'TERM': 'linux', 03:24:59 INFO - 'TMOUT': '86400', 03:24:59 INFO - 'USER': 'cltbld', 03:24:59 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:59 INFO - '_': '/tools/buildbot/bin/python'} 03:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:25:00 INFO - Cleaning up... 03:25:00 INFO - Return code: 0 03:25:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:25:00 INFO - Using env: {'DISPLAY': ':0', 03:25:00 INFO - 'HOME': '/home/cltbld', 03:25:00 INFO - 'LANG': 'en_US.UTF-8', 03:25:00 INFO - 'LANGUAGE': 'en_US:en', 03:25:00 INFO - 'LOGNAME': 'cltbld', 03:25:00 INFO - 'MAIL': '/var/mail/cltbld', 03:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:00 INFO - 'MOZ_NO_REMOTE': '1', 03:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:00 INFO - 'NO_EM_RESTART': '1', 03:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:00 INFO - 'PWD': '/builds/slave/test', 03:25:00 INFO - 'SHELL': '/bin/bash', 03:25:00 INFO - 'SHLVL': '1', 03:25:00 INFO - 'TERM': 'linux', 03:25:00 INFO - 'TMOUT': '86400', 03:25:00 INFO - 'USER': 'cltbld', 03:25:00 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:00 INFO - '_': '/tools/buildbot/bin/python'} 03:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:02 INFO - Downloading blobuploader-1.2.4.tar.gz 03:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:25:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:25:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:02 INFO - Downloading docopt-0.6.1.tar.gz 03:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:25:03 INFO - Installing collected packages: blobuploader, requests, docopt 03:25:03 INFO - Running setup.py install for blobuploader 03:25:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:25:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:25:03 INFO - Running setup.py install for requests 03:25:03 INFO - Running setup.py install for docopt 03:25:03 INFO - Successfully installed blobuploader requests docopt 03:25:03 INFO - Cleaning up... 03:25:03 INFO - Return code: 0 03:25:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:25:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:25:03 INFO - Using env: {'DISPLAY': ':0', 03:25:03 INFO - 'HOME': '/home/cltbld', 03:25:03 INFO - 'LANG': 'en_US.UTF-8', 03:25:03 INFO - 'LANGUAGE': 'en_US:en', 03:25:03 INFO - 'LOGNAME': 'cltbld', 03:25:03 INFO - 'MAIL': '/var/mail/cltbld', 03:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:03 INFO - 'MOZ_NO_REMOTE': '1', 03:25:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:03 INFO - 'NO_EM_RESTART': '1', 03:25:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:03 INFO - 'PWD': '/builds/slave/test', 03:25:03 INFO - 'SHELL': '/bin/bash', 03:25:03 INFO - 'SHLVL': '1', 03:25:03 INFO - 'TERM': 'linux', 03:25:03 INFO - 'TMOUT': '86400', 03:25:03 INFO - 'USER': 'cltbld', 03:25:03 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:03 INFO - '_': '/tools/buildbot/bin/python'} 03:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:03 INFO - Running setup.py (path:/tmp/pip-5HJpXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:03 INFO - Running setup.py (path:/tmp/pip-8YWf2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:03 INFO - Running setup.py (path:/tmp/pip-2FjvAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:04 INFO - Running setup.py (path:/tmp/pip-El603K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:04 INFO - Running setup.py (path:/tmp/pip-RGmdXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:04 INFO - Running setup.py (path:/tmp/pip-J5jJ_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:04 INFO - Running setup.py (path:/tmp/pip-md9Dx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:04 INFO - Running setup.py (path:/tmp/pip-UAG6VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:04 INFO - Running setup.py (path:/tmp/pip-pnZhvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:04 INFO - Running setup.py (path:/tmp/pip-FPZSfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:04 INFO - Running setup.py (path:/tmp/pip-9QVG3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:04 INFO - Running setup.py (path:/tmp/pip-ViD3oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:04 INFO - Running setup.py (path:/tmp/pip-sDy_dF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:04 INFO - Running setup.py (path:/tmp/pip-Uveh0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:04 INFO - Running setup.py (path:/tmp/pip-fy6XOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:04 INFO - Running setup.py (path:/tmp/pip-H3IjhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:04 INFO - Running setup.py (path:/tmp/pip-hpzxV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:25:05 INFO - Running setup.py install for manifestparser 03:25:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozcrash 03:25:05 INFO - Running setup.py install for mozdebug 03:25:05 INFO - Running setup.py install for mozdevice 03:25:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozfile 03:25:05 INFO - Running setup.py install for mozhttpd 03:25:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozinfo 03:25:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozInstall 03:25:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:25:05 INFO - Running setup.py install for mozleak 03:25:05 INFO - Running setup.py install for mozlog 03:25:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for moznetwork 03:25:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for mozprocess 03:25:06 INFO - Running setup.py install for mozprofile 03:25:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for mozrunner 03:25:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Running setup.py install for mozscreenshot 03:25:06 INFO - Running setup.py install for moztest 03:25:06 INFO - Running setup.py install for mozversion 03:25:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:25:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:25:06 INFO - Cleaning up... 03:25:06 INFO - Return code: 0 03:25:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:25:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:25:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:25:06 INFO - Using env: {'DISPLAY': ':0', 03:25:06 INFO - 'HOME': '/home/cltbld', 03:25:06 INFO - 'LANG': 'en_US.UTF-8', 03:25:06 INFO - 'LANGUAGE': 'en_US:en', 03:25:06 INFO - 'LOGNAME': 'cltbld', 03:25:06 INFO - 'MAIL': '/var/mail/cltbld', 03:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:06 INFO - 'MOZ_NO_REMOTE': '1', 03:25:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:06 INFO - 'NO_EM_RESTART': '1', 03:25:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:06 INFO - 'PWD': '/builds/slave/test', 03:25:06 INFO - 'SHELL': '/bin/bash', 03:25:06 INFO - 'SHLVL': '1', 03:25:06 INFO - 'TERM': 'linux', 03:25:06 INFO - 'TMOUT': '86400', 03:25:06 INFO - 'USER': 'cltbld', 03:25:06 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:06 INFO - '_': '/tools/buildbot/bin/python'} 03:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:07 INFO - Running setup.py (path:/tmp/pip-TNOjAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:07 INFO - Running setup.py (path:/tmp/pip-LwY7uZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:07 INFO - Running setup.py (path:/tmp/pip-fJwAhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:07 INFO - Running setup.py (path:/tmp/pip-BaibVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:07 INFO - Running setup.py (path:/tmp/pip-RBaNct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:07 INFO - Running setup.py (path:/tmp/pip-pD9lM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:07 INFO - Running setup.py (path:/tmp/pip-ydZK9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:07 INFO - Running setup.py (path:/tmp/pip-uNDqXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:07 INFO - Running setup.py (path:/tmp/pip-Xd7lBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:07 INFO - Running setup.py (path:/tmp/pip-FiFdRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:07 INFO - Running setup.py (path:/tmp/pip-9u3wy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:07 INFO - Running setup.py (path:/tmp/pip-tRYKfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:25:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:07 INFO - Running setup.py (path:/tmp/pip-Mb7DbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:08 INFO - Running setup.py (path:/tmp/pip-kwe39f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:08 INFO - Running setup.py (path:/tmp/pip-BXTaDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:08 INFO - Running setup.py (path:/tmp/pip-uf6cur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:25:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:08 INFO - Running setup.py (path:/tmp/pip-9hVsHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:10 INFO - Downloading blessings-1.6.tar.gz 03:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:25:10 INFO - Installing collected packages: blessings 03:25:10 INFO - Running setup.py install for blessings 03:25:10 INFO - Successfully installed blessings 03:25:10 INFO - Cleaning up... 03:25:10 INFO - Return code: 0 03:25:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:25:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:10 INFO - Reading from file tmpfile_stdout 03:25:10 INFO - Current package versions: 03:25:10 INFO - argparse == 1.2.1 03:25:10 INFO - blessings == 1.6 03:25:10 INFO - blobuploader == 1.2.4 03:25:10 INFO - docopt == 0.6.1 03:25:10 INFO - functools32 == 3.2.3-2 03:25:10 INFO - jsonschema == 2.5.1 03:25:10 INFO - manifestparser == 1.1 03:25:10 INFO - mozInstall == 1.12 03:25:10 INFO - mozcrash == 0.17 03:25:10 INFO - mozdebug == 0.1 03:25:10 INFO - mozdevice == 0.48 03:25:10 INFO - mozfile == 1.2 03:25:10 INFO - mozhttpd == 0.7 03:25:10 INFO - mozinfo == 0.9 03:25:10 INFO - mozleak == 0.1 03:25:10 INFO - mozlog == 3.3 03:25:10 INFO - moznetwork == 0.27 03:25:10 INFO - mozprocess == 0.23 03:25:10 INFO - mozprofile == 0.28 03:25:10 INFO - mozrunner == 6.12 03:25:10 INFO - mozscreenshot == 0.1 03:25:10 INFO - mozsystemmonitor == 0.3 03:25:10 INFO - moztest == 0.7 03:25:10 INFO - mozversion == 1.4 03:25:10 INFO - psutil == 3.1.1 03:25:10 INFO - requests == 1.2.3 03:25:10 INFO - wsgiref == 0.1.2 03:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:25:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:25:10 INFO - Using env: {'DISPLAY': ':0', 03:25:10 INFO - 'HOME': '/home/cltbld', 03:25:10 INFO - 'LANG': 'en_US.UTF-8', 03:25:10 INFO - 'LANGUAGE': 'en_US:en', 03:25:10 INFO - 'LOGNAME': 'cltbld', 03:25:10 INFO - 'MAIL': '/var/mail/cltbld', 03:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:10 INFO - 'MOZ_NO_REMOTE': '1', 03:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:10 INFO - 'NO_EM_RESTART': '1', 03:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:10 INFO - 'PWD': '/builds/slave/test', 03:25:10 INFO - 'SHELL': '/bin/bash', 03:25:10 INFO - 'SHLVL': '1', 03:25:10 INFO - 'TERM': 'linux', 03:25:10 INFO - 'TMOUT': '86400', 03:25:10 INFO - 'USER': 'cltbld', 03:25:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:10 INFO - '_': '/tools/buildbot/bin/python'} 03:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:25:10 INFO - Cleaning up... 03:25:10 INFO - Return code: 0 03:25:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f0030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26538a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:25:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:25:10 INFO - Using env: {'DISPLAY': ':0', 03:25:10 INFO - 'HOME': '/home/cltbld', 03:25:10 INFO - 'LANG': 'en_US.UTF-8', 03:25:10 INFO - 'LANGUAGE': 'en_US:en', 03:25:10 INFO - 'LOGNAME': 'cltbld', 03:25:10 INFO - 'MAIL': '/var/mail/cltbld', 03:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:10 INFO - 'MOZ_NO_REMOTE': '1', 03:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:10 INFO - 'NO_EM_RESTART': '1', 03:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:10 INFO - 'PWD': '/builds/slave/test', 03:25:10 INFO - 'SHELL': '/bin/bash', 03:25:10 INFO - 'SHLVL': '1', 03:25:10 INFO - 'TERM': 'linux', 03:25:10 INFO - 'TMOUT': '86400', 03:25:10 INFO - 'USER': 'cltbld', 03:25:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:10 INFO - '_': '/tools/buildbot/bin/python'} 03:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:25:11 INFO - Cleaning up... 03:25:11 INFO - Return code: 0 03:25:11 INFO - Running post-action listener: _resource_record_post_action 03:25:11 INFO - Running post-action listener: _start_resource_monitoring 03:25:11 INFO - Starting resource monitoring. 03:25:11 INFO - [mozharness: 2016-09-03 10:25:11.177831Z] Finished create-virtualenv step (success) 03:25:11 INFO - [mozharness: 2016-09-03 10:25:11.178263Z] Running install step. 03:25:11 INFO - Running pre-action listener: _resource_record_pre_action 03:25:11 INFO - Running main action method: install 03:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:11 INFO - Reading from file tmpfile_stdout 03:25:11 INFO - Detecting whether we're running mozinstall >=1.0... 03:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:25:11 INFO - Reading from file tmpfile_stdout 03:25:11 INFO - Output received: 03:25:11 INFO - Usage: mozinstall [options] installer 03:25:11 INFO - Options: 03:25:11 INFO - -h, --help show this help message and exit 03:25:11 INFO - -d DEST, --destination=DEST 03:25:11 INFO - Directory to install application into. [default: 03:25:11 INFO - "/builds/slave/test"] 03:25:11 INFO - --app=APP Application being installed. [default: firefox] 03:25:11 INFO - mkdir: /builds/slave/test/build/application 03:25:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:25:27 INFO - Reading from file tmpfile_stdout 03:25:27 INFO - Output received: 03:25:27 INFO - /builds/slave/test/build/application/firefox/firefox 03:25:27 INFO - Running post-action listener: _resource_record_post_action 03:25:27 INFO - [mozharness: 2016-09-03 10:25:27.502835Z] Finished install step (success) 03:25:27 INFO - [mozharness: 2016-09-03 10:25:27.503112Z] Running run-tests step. 03:25:27 INFO - Running pre-action listener: _resource_record_pre_action 03:25:27 INFO - Running main action method: run_tests 03:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:25:27 INFO - Python 2.7.3 03:25:27 INFO - Return code: 0 03:25:27 INFO - grabbing minidump binary from tooltool 03:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27af3b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:25:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:25:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:25:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:25:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:25:27 INFO - Return code: 0 03:25:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:25:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:25:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:25:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:25:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:25:27 INFO - Using env: {'DISPLAY': ':0', 03:25:27 INFO - 'HOME': '/home/cltbld', 03:25:27 INFO - 'LANG': 'en_US.UTF-8', 03:25:27 INFO - 'LANGUAGE': 'en_US:en', 03:25:27 INFO - 'LOGNAME': 'cltbld', 03:25:27 INFO - 'MAIL': '/var/mail/cltbld', 03:25:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:25:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:27 INFO - 'MOZ_NO_REMOTE': '1', 03:25:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:25:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:27 INFO - 'NO_EM_RESTART': '1', 03:25:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:27 INFO - 'PWD': '/builds/slave/test', 03:25:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:25:27 INFO - 'SHELL': '/bin/bash', 03:25:27 INFO - 'SHLVL': '1', 03:25:27 INFO - 'TERM': 'linux', 03:25:27 INFO - 'TMOUT': '86400', 03:25:27 INFO - 'USER': 'cltbld', 03:25:27 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299', 03:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:27 INFO - '_': '/tools/buildbot/bin/python'} 03:25:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:25:27 INFO - mozversion application_buildid: 20160902160050 03:25:27 INFO - mozversion application_changeset: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd 03:25:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:25:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:25:27 INFO - mozversion application_name: Firefox 03:25:27 INFO - mozversion application_remotingname: firefox-dev 03:25:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:25:27 INFO - mozversion application_vendor: Mozilla 03:25:27 INFO - mozversion application_version: 50.0a2 03:25:27 INFO - mozversion platform_buildid: 20160902160050 03:25:27 INFO - mozversion platform_changeset: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd 03:25:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:25:27 INFO - mozversion platform_version: 50.0a2 03:25:27 INFO - using testdate: 1472898327 03:25:27 INFO - actual date: 1472898327 03:25:27 INFO - starting webserver on 'localhost:59820' 03:25:27 INFO - SUITE-START | Running 2 tests 03:25:27 INFO - TEST-START | dromaeo_css 03:25:27 INFO - operating with platform_type : linux_ 03:25:27 INFO - Initialising browser for dromaeo_css test... 03:25:27 INFO - TEST-INFO | started process 21869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeUId7o/profile http://localhost:59820/getInfo.html) 03:25:31 INFO - PROCESS | 21869 | __metrics Screen width/height:1600/1200 03:25:31 INFO - PROCESS | 21869 | colorDepth:24 03:25:31 INFO - PROCESS | 21869 | Browser inner width/height: 1024/697 03:25:31 INFO - PROCESS | 21869 | __metrics 03:25:32 INFO - PROCESS | 21869 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:25:32 INFO - PROCESS | 21869 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:25:33 INFO - TEST-INFO | 21869: exit 0 03:25:33 INFO - Browser initialized. 03:25:33 INFO - Running cycle 1/1 for dromaeo_css test... 03:25:33 INFO - TEST-INFO | started process 21960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeUId7o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:44 INFO - PROCESS | 21960 | RSS: Main: 145367040 03:25:44 INFO - PROCESS | 21960 | 03:26:35 INFO - PROCESS | 21960 | 1472898395735 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:28:44 INFO - PROCESS | 21960 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59820/tests/dromaeo/cssquery-ext.html) 03:28:45 INFO - PROCESS | 21960 | RSS: Main: 222633984 03:28:45 INFO - PROCESS | 21960 | 03:31:48 INFO - PROCESS | 21960 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-ext.html (next: http://localhost:59820/tests/dromaeo/cssquery-jquery.html) 03:31:48 INFO - PROCESS | 21960 | RSS: Main: 223977472 03:31:48 INFO - PROCESS | 21960 | 03:34:50 INFO - PROCESS | 21960 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59820/tests/dromaeo/cssquery-mootools.html) 03:34:51 INFO - PROCESS | 21960 | RSS: Main: 204951552 03:34:51 INFO - PROCESS | 21960 | 03:37:53 INFO - PROCESS | 21960 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59820/tests/dromaeo/cssquery-prototype.html) 03:37:53 INFO - PROCESS | 21960 | RSS: Main: 201605120 03:37:53 INFO - PROCESS | 21960 | 03:40:56 INFO - PROCESS | 21960 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59820/tests/dromaeo/cssquery-yui.html) 03:40:56 INFO - PROCESS | 21960 | RSS: Main: 179118080 03:40:56 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | Cycle 1(1): loaded http://localhost:59820/tests/dromaeo/cssquery-yui.html (next: http://localhost:59820/tests/dromaeo/cssquery-dojo.html) 03:43:59 INFO - PROCESS | 21960 | RSS: Main: 269090816 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | __start_tp_report 03:43:59 INFO - PROCESS | 21960 | _x_x_mozilla_page_load 03:43:59 INFO - PROCESS | 21960 | _x_x_mozilla_page_load_details 03:43:59 INFO - PROCESS | 21960 | |i|pagename|runs| 03:43:59 INFO - PROCESS | 21960 | |0;dojo.html;3210.2616398228406;3230.806151630901;3232.3050544133007;3253.4469140246156;3275.1157187559343;5625.437456254378;5702.344230413499;5709.2292540507015;5749.0226661467495;5752.309722833255;5658.43415658434;5713.942860571393;5717.914231286523;5719.513841323482;5725.971370143144;4992.450830408652;5086.84739457815;5136.306598609177;5150.948490515094;5204.1673332266655;5175.767090480937;5188.533032027117;5214.243934629477;5222.981518603874;5228.980348831979;5120.411152717429;5181.0674078665825;5189.143791274435;5198.298229738974;5222.268882356454;5268.8155914543195;5273.314469119009;5275.841724748264;5293.973530132324;5308.628396012281;4938.037082768861;5057.0897238497055;5117.488251174853;5178.948210517884;5182.481751824825;5578.135389014709;5594.636348637326;5653.321601407816;5664.575156863251;5679.290088738908;6090.025595904678;6119.785807496761;6130.785422510191;6132.601380910227;6134.294556126058;5907.113932910055;6044.002739548013;6088.786892458743;6101.725422356005;6167.537434692416;5742.167385729088;5756.107803290497;5763.6541807491685;5768.538516918637;5780.104083867007;6159.6905237824885;6213.5339849511465;6224.502039836802;6232.719527621268;6233;5848.327442344185;5972.343042265347;6035.7283922223605;6039.909401358983;6107.908381374283;6168.290646575701;6169.722362493699;6169.81490555284;6170.876582468415;6177;6122.2959359674205;6128.846778830476;6131.785387511416;6132;6141.048137538689;5830.446107619854;5997.55018373615;6051;6068.089786531968;6095.207623009069;4553.089382123589;4572.7942242599165;4587.541245875385;4612.60792832613;4617.607503362252;4159.688023398257;4169.520505141947;4172.457580514514;4172.624463798212;4204.285271503852;5576.191452239484;5752.194692743019;5793.7392817323325;5794.362620111784;5858.355580886099;6174.938250617436;6188.504919606421;6190.102435146879;6190.350013248544;6191.1641928339095;5833.766649334064;5834.970825145847;5836.358000619928;5839.708014599253;5851.444112809277;5088.491150884882;5223.033738758342;5252.789888404497;5272.288241087481;5338.679679219259;6314.605511344606;6328.905066423915;6334.144890439732;6352.587081839665;6358.1416508771645;6125.84685382869;6132.233470816148;6135.601185922901;6139.785107521216;6143.8326717923455;5998.70006499682;6188.1027251049245;6202.41077097675;6227.532935029982;6238.407351301619;5973.412846316312;5980.641161530322;5985.072313791369;6014.0678194879865;6038.365971572922;6136.447719705248;6145.508359331332;6146.969265153645;6149.2620885494025;6153.107799369156;5809.8547536311935;5910.763569457174;5964.165016897725;5975.820725378246;6063.242094738158;5936.1689363488285;5995.310539288038;6002.759889604542;6004.639721616717;6007.12896629995;5843.328017278068;5856.238688970577;5888.352281248974;5910.083936989773;5972.223610930551;5808.215890854681;6009.489193418433;6050.5764596479585;6079.209702738615;6086.391360863879;5866.560007999503;5881.088431293157;5883.117532370007;5884.205632239593;5884.735186916513 03:43:59 INFO - PROCESS | 21960 | |1;ext.html;617.5491890919628;624.2664868779185;665.1120753793685;667.9732810687572;669.180254188619;34522.48216276755;46741;47509.76245118769;47601;47678.52321476784;49718.50281497184;50017.49982500174;50153.49846501534;50165.24752128712;50236;8521.36089793268;9230.815383692323;9373.156415922565;9395;10995.780084398348;10851.077658399006;11247.381394023325;11255.155863310243;11267.436628168558;11270.267432616894;11321;11352.262102963281;11396.088312934986;11405.600803971862;11406.71483212918;11150.665480035612;11152.721181970435;11154;11202.887971120266;11216.383098929597;11172.720681983015;11246.718832029264;11247.044001259903;11249.60626378081;11253.662390128227;46100.3084953726;46428.30357544639;46513.302300465184;46515;46532;3684.5394325709285;3878.747881387729;3911.706622003361;4158.59241510204;4316.136772645452;35562.46656300157;35612.64387356119;35639.82180089109;36105;36356.454653180226;4638.46657634373;4645.814167433298;4683.906321873577;4686.625069994427;4723.149833030053;386974;391436;392417;392501;393324;38192.23615527674;63151;63214;63602.681986590695;63737.681311594075;40014;41860.79069604693;42003.36994945078;42020.789896050934;42044.78977605092;14806.851931480764;15068.849311506963;15103;15132.243387830564;15950.680986380208;10723.731906702238;11857.229280096939;12057.035437165006;12104.273743575413;12188;9308.44149351041;9322.487263200454;9324.766880827898;9383.249340052917;9389.859152112724;3522.647735226457;3577.266660334625;3607.1162565171703;3609.1157666371905;3648.9635103648625;1093.3002878157986;1124.8256520239377;1129.48043899807;1129.5538262386312;1209.5766481731323;34703.30593388169;35265;35764;35957.820210898775;36718.449223261676;1075.381655548063;1087.521490544158;1090.9890901091046;1106.8284415915384;1134.0813940708053;17385.130743462778;18109.909450452928;18121.728174077398;18217.544561385806;18264.726029109574;616744;619804;620164;623301;625548;63035;63276;63635.681821590595;63644.68177659082;63816.68091659512;1368.261138984937;1455.046944251517;1456.788765628978;1482.4811316039516;1521.3078049487456;34054;36487.604739087816;36561.63438365582;36627;36706.63293367032;54896.176557350875;55709.442905570424;56129.43870561242;56150.71924640514;56265.15602265888;454935;457407;457611;458091;458998;29920.25199370033;31622.209444764067;31984.52023219607;33403.83298083494;33621.495677564366;54417.18374224469;54619.72690136524;54978.45021549733;54978.725106374215;55409.1688624679;44091.338629919934;44363.140393842536;44887.55112448834;46581.76709116433;46800.297995530775;45158;45560;46398.76800615976;46615;47450.76274618605;45886;46094;46537;47288.763556182;47376.05247894954;37534.6246537542;43416;43539.78230108829;43648.18897323606;43848.56151438445;29175.562366564944;30013.049151805062;30931.84534077315;31483.527747083353;31980.840095799373 03:43:59 INFO - PROCESS | 21960 | |2;jquery.html;398.6551632837593;412.39378114172155;416.566770558619;417.4552209366776;435.4730775761329;15678.921605391972;20090.296839610615;20385.28651497198;20631.793682063177;22367.993440295224;21097.15611375547;21198.89400552995;21341.893290533564;21536.246231381905;21544.13823447064;5534.806281780128;5849.736761845727;5861.062230043194;5869.50109240715;5887.470127688507;4376.321670141133;4568.725876447424;5781.13283007548;5847.590668653196;5948.613340132899;6573.112629794989;6590.209174898995;6710;6720.932790672079;6724.125863637744;6490.870182596345;6698.832529186785;6704.329567043256;6718.025886246492;6755.088063111466;5888.116782482619;5970.044792833126;5979.760809567612;5981.970090149565;5991.880162396771;25555.977760889542;25640.358991025187;25647.48705025889;25734.356641083934;25754.48491030169;2682.785377169822;2706.0393560286207;2751.257160566656;2805.9158225253277;3184.570083038783;15293.158876261801;15578.610534736723;15685.843141568552;16250.51248462548;16346.75479867803;2063.484128967758;2134.1890081768984;2136.59404713104;2143.442704896738;2195.9560808783895;55576.65601829334;61864.38135618631;64901;69649;85020.57489712512;10517.211209159344;11538.250013749079;11571.76856462866;11579.594714185132;11699;15909.443169489012;16054.598635034217;16274;16280.674386512203;16294.837051629333;8417.28453081495;8426.915730842737;8498.617562209716;8545.487270763773;8684.088170742107;8019.15798841125;8126.512409255463;8154.69524876017;8302.584870756438;8360.958195208985;6917.377436030782;7052.612106334201;7059;7114.64426778659;7154.676384868816;2004.849636277285;2008.8348757720487;2035.9389218323474;2079.563291708728;2079.854410191302;1008.0927165551062;1031.8400647899587;1061.2147011211803;1079.3362082319318;1110.228391268061;21429.142834286453;21575;21775.649345805596;21815.765857334973;21967.391684529928;1072.4316112460408;1078.320657985464;1091.0671375973586;1096.5065720425841;1109.2623405435456;11098;12567.245965242115;12703.237805731686;12724.491020359266;12813.743725125632;529774;536393;548408;558536;564612;83928;87869.56065219762;89395.55302223447;89653.55173224091;89848.5507572471;1070.5931745936493;1076.3004047369116;1078.1105587890022;1081.2809481694742;1088.21648413144;18961.95709236005;19184.71222931685;19302.7104593434;19362.515937101685;19687.40937771812;43788.34317485177;43889.34165987449;43894.56105438905;45073.54926450693;47068.29397559108;60137.39862601493;61572;62440;63771;71170.64414677894;8937.776555586162;9171.312151588523;9294.488803115622;9498.19265362452;9516.476593787409;13393.933030334785;14147;14331.713365732836;14700.705985880437;14839.40642374293;12200.51197952072;12607.558735444216;12641;12662;12721.173123746927;11908.761824763284;12093.637190884288;12229.205101668364;12551.811722823983;12639.304838233986;12402.13185077036;12627.494900203888;12628.810567841307;12745.235285882876;12840.55058072963;9146.58840352199;9244.583993720164;9250.398724082914;9454.905450945402;9545.665901693408;12222.511099555919;12374.62876113718;12642.747145057232;12674.366281685694;12831.615051548468 03:43:59 INFO - PROCESS | 21960 | |3;mootools.html;1377.7520046391658;1468.8384277729451;1560.2978659603177;1719.1060648462799;1882.7646544181978;9223.30825188111;9627.807443851127;9702.223822094224;9949.054839790226;9951.154151897097;9779.804403911918;9842.60629574816;9892.554835032422;9893.505324733771;9899.950500247507;2583.470388570348;2621.488809682113;2622.921312360623;2624.3832699315644;2626.146502386722;2496.2636022373445;2563.6410902473576;2566.178822776703;2569.1264969910226;2572;2515.5094756522485;2521.596544552872;2548.324693956103;2548.9745102548923;2555;2457.410221546834;2480.08236952327;2487.8507289562685;2499.550080985422;2506.736792636765;2453.914113006036;2480.7643273888953;2494.6382774497597;2510.5104504621604;2519.10571747029;9297.72106836796;9694.660686875926;9804.166645835112;9839.360441571274;9890.9505452473;5680.914786278209;5830.970845145747;5877.5591830612875;5886.470217680429;5897.705114744289;3658.981705091484;3663.1757854482794;3705.314516814398;3731.5149030625844;3739.5138631977943;1218.8476440444945;1229.6987238126626;1237.22055105284;1266.6643339515035;1275.349571718421;14007;14032;14096.577102686935;14100.85899141016;14119.717605647933;4814.614830813596;4857.562819346205;4952.727599981963;4990.326305948651;5037.546620804145;10411.271211015308;10425.530851111718;10435.32170408921;10452.843207351896;10473.266871319087;5791.913121303184;5801.042827933344;5807.68057756819;5809.506191973645;5818.359980402152;4620.768961551909;4737.081481999727;4835.637327200474;5203.947960520346;5409.621326507186;4157.729747566398;4172.269852775752;4239.660827133822;4264.147170565837;4280.807363668642;1220.4385982447996;1246.4827096754927;1276.0365923727527;1278.1308710077237;1288.323630094208;1038.5482315192914;1046.2676126711333;1049.7742985258205;1055.794120146564;1067.066316972649;6521.152250207538;6533;6625.370589793962;6744.83137921546;6823.69293381799;494.1081348166522;504.53078636868065;987.0474991633108;991.7272749993809;1018.9643362482426;4418.668599855024;4565;4568.817247310137;4584.312353147055;4627.791749371287;11829.88170118305;12154.149209555422;12170.208936419105;12420.192687475284;12502.3748812559;12139.757204855854;12370.381480925918;12482.251064936132;12567.057470689551;12608.432620532098;9018.819623607491;9049.954750226207;9172.44965302084;9183;9258.027907069662;6279.937200627935;6437.25971513282;6500.154979852589;6536.640484773383;6596.076549283008;11164.832527511931;11414.543418263176;11429.485673144894;11571.826422603835;11674.94162529182;10210.846837297595;10381.688549343531;11676.241044332091;11790.882091178979;11811.586594469152;7046.401055910305;7961.323287520626;7965.681372745257;8198.467099638505;8212.507249565046;8912;9040.819183616422;9110.590023449093;9135.954320228357;9149.176574108096;8623.525706086226;8626.353023523136;8648.091950345122;8734.388592798443;8821.514816685143;8385.496164229571;8491.617877195418;8555.61499732526;8627.654893804178;8735.650573977224;7821.530708157528;7937.682492700227;7948.205179482005;7950.443468957349;7951.920480795349;5686.872220278748;5789.797357092481;5829.358770535323;5846.269216347957;5939.613925094855;5608.6073974823;5671.206031155724;5736.799212027559;5779.133130030529;5952.196453478899 03:43:59 INFO - PROCESS | 21960 | |4;prototype.html;356.09196548800554;358.15475477872235;363.25713915043735;369.7993863114743;371.0519622364857;5582.776688932443;5602.831915042555;5605.971970140154;5622.662640241589;5645.689487078209;5243.610443232536;5300.966311569246;5442.537384322337;5510.610974250837;5538.9169162462495;4341.761203133827;4346.5436129206455;4357.891052723691;4396.538363471837;4406.647468202552;4344.023469321452;4356.869293921188;4368.97815510922;4394.274944634148;4405.471343438792;4167.979160104195;4280.4221430107;4288.270993931023;4306.363581838897;4366.97816510917;4275.315949448089;4283.892902677442;4297;4298.699091063627;4307.870763877088;3795.411711184771;3835.2137811748526;3842.1739326045035;3846.2115266370606;3847.5575308839284;5227.947720522822;5307.761150748225;5329;5417.322834645669;5433.918491222635;4922.507749225085;4926.531979461945;4944.9258261126115;4949.133901567247;4955.4548999610015;5704.258446401983;5731.541476681856;5732.398098199671;5733.655980641175;5735;4098.897527561805;4161.708680392374;4163.437935878648;4215.409842622035;4223.87328380146;5914.97042514789;5948.196993405922;5956.404359564052;5964;5973.790917317917;4291.206126866539;4292.678049146327;4317.395564620955;4318.265894797892;4329.134173165379;4167.416561681367;4373.125374925028;4378.693491455568;4381.079973205598;4384.846530371422;4901.877453063631;4911.901761964741;4922.778474968635;4929.162042452792;4967.392119137409;5778.104393818965;5819.76720931158;5845.210896528914;5849.2103566018895;5854.385289544536;1456.3664805809506;1501.78974943508;1506.0662389318693;1507.2313120308502;1508.2911031815029;943.7829299261208;965.9565319560637;971.5385192033727;979.7060881735594;986.4870267461024;4965.652404331662;4974.975125124423;4995.850124496198;5005.749712514433;5025.924611130836;5190.780166660832;5216.60875434344;5216.7395377888815;5219.712915789592;5259.815906443256;4834.516548345137;4937.901241975141;4998.125328067574;4999.275394909783;5014.674046186986;2771.708970558103;2785.701796018293;2811.6204312417563;2812.5078111330436;2814.4520513717375;5362.65142765719;5363.873586546791;5379.246905433242;5388.245645609618;5392.73036348181;4959.8840260941;5274.314339135888;5308.593222795962;5322.0154271459905;5337.450595354124;5397.596624877481;5401.432849550741;5429.484199001167;5432.945670543323;5449.509544141047;5197.818076367309;5246.711430871339;5266.0257852297445;5278.287431196739;5296.470352964673;5251.083354575476;5526.751296191762;5862.6256366171765;5891.231121372948;5911.467967882739;6100.298465676504;6153.784617538365;6176.567640265138;6213.291344880014;6226.159468471698;4304.677149213759;4398.868033958986;4472.865814025585;4476.306172543261;4480.39514665529;5851.736671849862;5853.131965337039;5869.6771677558145;5892.263467066617;5913.438223368773;5732.426757324234;5751.763370875282;5758.942410575841;5815.9709201453725;5825.446582574648;5654.491095801398;5720.971395142998;5740.052891272893;5740.225069615549;5780.2485676863425;5714.857128571819;5719.714014299185;5721.5136713379825;5729.4843464088435;5730.713464326684;5622.88754224905;5814.924239015796;5826.854328641818;5841.18223448726;5858.443447872445;5654.635491882324;5673.574481913791;5679.630823996427;5686.2323586315315;5690.630109042899 03:43:59 INFO - PROCESS | 21960 | |5;yui.html;804.2681160144269;822.6873787960578;890.1899271662788;893.9240164586009;896.3143195455474;16439.2602332895;17542.298308067693;17564;17570.560735981606;17571.648567028653;16201.270942807572;16203.675926481463;16362.918185409057;16385.67228655426;16388.180590970464;722.1911459165729;848.9915100848974;871.790770215147;875.0287181228831;878.7978764884081;878.8374150782093;879.5206612396228;880.8018195905934;881.2509367037999;881.7487016200384;932.5943214701626;933.0296491648677;934.1265916368184;935.0555938502093;935.3078721745927;753.2693287511133;898.6135961536504;921.4195057114042;925.22281283722;930.7393929699695;679.6839469646588;682.0723815610766;682.6757290287073;683.2484267305973;683.3986092238848;14448.277586120757;14482.49311274111;14532.200728959902;14544.27278636064;14571.48999785013;14767.335469903772;15003.774943375858;15022.399104035825;15168.24158792056;15698;14205.502807401692;14380.209088500129;14413.639659008504;14582.125072495684;14611.707765844623;4604.746738929391;4638.721676699409;4689.671722979393;4748.38271024768;4790.257510085942;87677.12322876754;88625.99999999936;89179;89243;89254;12585.433655485525;12652.81020784689;12689.746205075848;12756.425960831784;12928.418221180069;22462.887685561793;22474.77525224727;22481.662775058387;22526.21158259453;22546.887265563568;9062.320325975577;9165;9348.672796452092;9462.952685236529;9467.337286390024;4961.975190124026;5104.744762761847;5128.589712822965;5133.332666753298;5265.0259701955265;3503.491993660905;3607.6933460655614;3611.94582081269;3619.6561326673923;3622.601513833476;585.28887401807;680.425040840489;694.2224708326706;708.8830342993451;711.1892442615423;733.2154594583744;770.1989930472358;796.4026979765176;804.6821505505293;816.2775943290231;10099.899001010042;10835.83746243807;10918.563257469612;10922.672319830259;10996.0653344465;610.7770663707714;700.3976580141075;765.1239330966013;769;779.6296759039411;5150.97424512875;5299;5301.390340110936;5315.176147697113;5339.252504649352;122628;125851;129488.35255823661;132203.33898330448;133546;106203.46898265614;108365.4581727102;115227.42386288183;117903;118066.8193318073;76920.23079769242;100780.49609751905;101707.49146254221;103438.96561034293;104768.47615761873;10073.221861246075;10092.949535252277;10180.949095254477;10256.641017564349;10324.22568307395;50764;52516.212256815415;56171;59687;61075;75603;78284.60857695674;78385.60807195927;78826.6058669703;79858.60070699609;14617.19605421712;15511.224438778241;15605.687886242438;15715.507026832433;16022.839771602135;17320.91339543294;17354.913225433793;18615.162317695464;19181.520461988002;19360.70958935645;15138.545843624708;15218.239088045775;15746.606334841721;15841.287142078403;16085.91957040254;15666.608334791721;15690.450834220748;15828.52514424569;15863.206839658202;15917.204139792733;15696;15814.051156930622;15855.286512107214;16039.518814435585;16858.662826743643;12466.283511581669;12467.314297713712;12497.93751031239;13424;13473.797893031417;12054.457549410474;12837.550685725955;12895.16181448203;12947.352632368533;13097.279649618982 03:43:59 INFO - PROCESS | 21960 | __end_tp_report 03:43:59 INFO - PROCESS | 21960 | __start_cc_report 03:43:59 INFO - PROCESS | 21960 | _x_x_mozilla_cycle_collect,980 03:43:59 INFO - PROCESS | 21960 | __end_cc_report 03:43:59 INFO - PROCESS | 21960 | __startTimestamp1472899439709__endTimestamp 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | ------- Summary: start ------- 03:43:59 INFO - PROCESS | 21960 | Number of tests: 6 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | [#0] dojo.html Cycles:165 Average:5664.38 Median:5881.09 stddev:656.00 (11.2%) stddev-sans-first:629.12 03:43:59 INFO - PROCESS | 21960 | Values: 3210.3 3230.8 3232.3 3253.4 3275.1 5625.4 5702.3 5709.2 5749.0 5752.3 5658.4 5713.9 5717.9 5719.5 5726.0 4992.5 5086.8 5136.3 5150.9 5204.2 5175.8 5188.5 5214.2 5223.0 5229.0 5120.4 5181.1 5189.1 5198.3 5222.3 5268.8 5273.3 5275.8 5294.0 5308.6 4938.0 5057.1 5117.5 5178.9 5182.5 5578.1 5594.6 5653.3 5664.6 5679.3 6090.0 6119.8 6130.8 6132.6 6134.3 5907.1 6044.0 6088.8 6101.7 6167.5 5742.2 5756.1 5763.7 5768.5 5780.1 6159.7 6213.5 6224.5 6232.7 6233.0 5848.3 5972.3 6035.7 6039.9 6107.9 6168.3 6169.7 6169.8 6170.9 6177.0 6122.3 6128.8 6131.8 6132.0 6141.0 5830.4 5997.6 6051.0 6068.1 6095.2 4553.1 4572.8 4587.5 4612.6 4617.6 4159.7 4169.5 4172.5 4172.6 4204.3 5576.2 5752.2 5793.7 5794.4 5858.4 6174.9 6188.5 6190.1 6190.4 6191.2 5833.8 5835.0 5836.4 5839.7 5851.4 5088.5 5223.0 5252.8 5272.3 5338.7 6314.6 6328.9 6334.1 6352.6 6358.1 6125.8 6132.2 6135.6 6139.8 6143.8 5998.7 6188.1 6202.4 6227.5 6238.4 5973.4 5980.6 5985.1 6014.1 6038.4 6136.4 6145.5 6147.0 6149.3 6153.1 5809.9 5910.8 5964.2 5975.8 6063.2 5936.2 5995.3 6002.8 6004.6 6007.1 5843.3 5856.2 5888.4 5910.1 5972.2 5808.2 6009.5 6050.6 6079.2 6086.4 5866.6 5881.1 5883.1 5884.2 5884.7 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | [#1] ext.html Cycles:180 Average:65605.46 Median:392912.50 stddev:132736.18 (33.8%) stddev-sans-first:133018.85 03:43:59 INFO - PROCESS | 21960 | Values: 617.5 624.3 665.1 668.0 669.2 34522.5 46741.0 47509.8 47601.0 47678.5 49718.5 50017.5 50153.5 50165.2 50236.0 8521.4 9230.8 9373.2 9395.0 10995.8 10851.1 11247.4 11255.2 11267.4 11270.3 11321.0 11352.3 11396.1 11405.6 11406.7 11150.7 11152.7 11154.0 11202.9 11216.4 11172.7 11246.7 11247.0 11249.6 11253.7 46100.3 46428.3 46513.3 46515.0 46532.0 3684.5 3878.7 3911.7 4158.6 4316.1 35562.5 35612.6 35639.8 36105.0 36356.5 4638.5 4645.8 4683.9 4686.6 4723.1 386974.0 391436.0 392417.0 392501.0 393324.0 38192.2 63151.0 63214.0 63602.7 63737.7 40014.0 41860.8 42003.4 42020.8 42044.8 14806.9 15068.8 15103.0 15132.2 15950.7 10723.7 11857.2 12057.0 12104.3 12188.0 9308.4 9322.5 9324.8 9383.2 9389.9 3522.6 3577.3 3607.1 3609.1 3649.0 1093.3 1124.8 1129.5 1129.6 1209.6 34703.3 35265.0 35764.0 35957.8 36718.4 1075.4 1087.5 1091.0 1106.8 1134.1 17385.1 18109.9 18121.7 18217.5 18264.7 616744.0 619804.0 620164.0 623301.0 625548.0 63035.0 63276.0 63635.7 63644.7 63816.7 1368.3 1455.0 1456.8 1482.5 1521.3 34054.0 36487.6 36561.6 36627.0 36706.6 54896.2 55709.4 56129.4 56150.7 56265.2 454935.0 457407.0 457611.0 458091.0 458998.0 29920.3 31622.2 31984.5 33403.8 33621.5 54417.2 54619.7 54978.5 54978.7 55409.2 44091.3 44363.1 44887.6 46581.8 46800.3 45158.0 45560.0 46398.8 46615.0 47450.8 45886.0 46094.0 46537.0 47288.8 47376.1 37534.6 43416.0 43539.8 43648.2 43848.6 29175.6 30013.0 30931.8 31483.5 31980.8 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | [#2] jquery.html Cycles:180 Average:31194.92 Median:25598.17 stddev:89692.53 (350.4%) stddev-sans-first:89914.34 03:43:59 INFO - PROCESS | 21960 | Values: 398.7 412.4 416.6 417.5 435.5 15678.9 20090.3 20385.3 20631.8 22368.0 21097.2 21198.9 21341.9 21536.2 21544.1 5534.8 5849.7 5861.1 5869.5 5887.5 4376.3 4568.7 5781.1 5847.6 5948.6 6573.1 6590.2 6710.0 6720.9 6724.1 6490.9 6698.8 6704.3 6718.0 6755.1 5888.1 5970.0 5979.8 5982.0 5991.9 25556.0 25640.4 25647.5 25734.4 25754.5 2682.8 2706.0 2751.3 2805.9 3184.6 15293.2 15578.6 15685.8 16250.5 16346.8 2063.5 2134.2 2136.6 2143.4 2196.0 55576.7 61864.4 64901.0 69649.0 85020.6 10517.2 11538.3 11571.8 11579.6 11699.0 15909.4 16054.6 16274.0 16280.7 16294.8 8417.3 8426.9 8498.6 8545.5 8684.1 8019.2 8126.5 8154.7 8302.6 8361.0 6917.4 7052.6 7059.0 7114.6 7154.7 2004.8 2008.8 2035.9 2079.6 2079.9 1008.1 1031.8 1061.2 1079.3 1110.2 21429.1 21575.0 21775.6 21815.8 21967.4 1072.4 1078.3 1091.1 1096.5 1109.3 11098.0 12567.2 12703.2 12724.5 12813.7 529774.0 536393.0 548408.0 558536.0 564612.0 83928.0 87869.6 89395.6 89653.6 89848.6 1070.6 1076.3 1078.1 1081.3 1088.2 18962.0 19184.7 19302.7 19362.5 19687.4 43788.3 43889.3 43894.6 45073.5 47068.3 60137.4 61572.0 62440.0 63771.0 71170.6 8937.8 9171.3 9294.5 9498.2 9516.5 13393.9 14147.0 14331.7 14700.7 14839.4 12200.5 12607.6 12641.0 12662.0 12721.2 11908.8 12093.6 12229.2 12551.8 12639.3 12402.1 12627.5 12628.8 12745.2 12840.6 9146.6 9244.6 9250.4 9454.9 9545.7 12222.5 12374.6 12642.7 12674.4 12831.6 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | [#3] mootools.html Cycles:180 Average:6378.85 Median:4624.28 stddev:3697.47 (80.0%) stddev-sans-first:3688.74 03:43:59 INFO - PROCESS | 21960 | Values: 1377.8 1468.8 1560.3 1719.1 1882.8 9223.3 9627.8 9702.2 9949.1 9951.2 9779.8 9842.6 9892.6 9893.5 9900.0 2583.5 2621.5 2622.9 2624.4 2626.1 2496.3 2563.6 2566.2 2569.1 2572.0 2515.5 2521.6 2548.3 2549.0 2555.0 2457.4 2480.1 2487.9 2499.6 2506.7 2453.9 2480.8 2494.6 2510.5 2519.1 9297.7 9694.7 9804.2 9839.4 9891.0 5680.9 5831.0 5877.6 5886.5 5897.7 3659.0 3663.2 3705.3 3731.5 3739.5 1218.8 1229.7 1237.2 1266.7 1275.3 14007.0 14032.0 14096.6 14100.9 14119.7 4814.6 4857.6 4952.7 4990.3 5037.5 10411.3 10425.5 10435.3 10452.8 10473.3 5791.9 5801.0 5807.7 5809.5 5818.4 4620.8 4737.1 4835.6 5203.9 5409.6 4157.7 4172.3 4239.7 4264.1 4280.8 1220.4 1246.5 1276.0 1278.1 1288.3 1038.5 1046.3 1049.8 1055.8 1067.1 6521.2 6533.0 6625.4 6744.8 6823.7 494.1 504.5 987.0 991.7 1019.0 4418.7 4565.0 4568.8 4584.3 4627.8 11829.9 12154.1 12170.2 12420.2 12502.4 12139.8 12370.4 12482.3 12567.1 12608.4 9018.8 9050.0 9172.4 9183.0 9258.0 6279.9 6437.3 6500.2 6536.6 6596.1 11164.8 11414.5 11429.5 11571.8 11674.9 10210.8 10381.7 11676.2 11790.9 11811.6 7046.4 7961.3 7965.7 8198.5 8212.5 8912.0 9040.8 9110.6 9136.0 9149.2 8623.5 8626.4 8648.1 8734.4 8821.5 8385.5 8491.6 8555.6 8627.7 8735.7 7821.5 7937.7 7948.2 7950.4 7951.9 5686.9 5789.8 5829.4 5846.3 5939.6 5608.6 5671.2 5736.8 5779.1 5952.2 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | [#4] prototype.html Cycles:180 Average:4740.76 Median:5262.92 stddev:1361.24 (25.9%) stddev-sans-first:1324.67 03:43:59 INFO - PROCESS | 21960 | Values: 356.1 358.2 363.3 369.8 371.1 5582.8 5602.8 5606.0 5622.7 5645.7 5243.6 5301.0 5442.5 5510.6 5538.9 4341.8 4346.5 4357.9 4396.5 4406.6 4344.0 4356.9 4369.0 4394.3 4405.5 4168.0 4280.4 4288.3 4306.4 4367.0 4275.3 4283.9 4297.0 4298.7 4307.9 3795.4 3835.2 3842.2 3846.2 3847.6 5227.9 5307.8 5329.0 5417.3 5433.9 4922.5 4926.5 4944.9 4949.1 4955.5 5704.3 5731.5 5732.4 5733.7 5735.0 4098.9 4161.7 4163.4 4215.4 4223.9 5915.0 5948.2 5956.4 5964.0 5973.8 4291.2 4292.7 4317.4 4318.3 4329.1 4167.4 4373.1 4378.7 4381.1 4384.8 4901.9 4911.9 4922.8 4929.2 4967.4 5778.1 5819.8 5845.2 5849.2 5854.4 1456.4 1501.8 1506.1 1507.2 1508.3 943.8 966.0 971.5 979.7 986.5 4965.7 4975.0 4995.9 5005.7 5025.9 5190.8 5216.6 5216.7 5219.7 5259.8 4834.5 4937.9 4998.1 4999.3 5014.7 2771.7 2785.7 2811.6 2812.5 2814.5 5362.7 5363.9 5379.2 5388.2 5392.7 4959.9 5274.3 5308.6 5322.0 5337.5 5397.6 5401.4 5429.5 5432.9 5449.5 5197.8 5246.7 5266.0 5278.3 5296.5 5251.1 5526.8 5862.6 5891.2 5911.5 6100.3 6153.8 6176.6 6213.3 6226.2 4304.7 4398.9 4472.9 4476.3 4480.4 5851.7 5853.1 5869.7 5892.3 5913.4 5732.4 5751.8 5758.9 5816.0 5825.4 5654.5 5721.0 5740.1 5740.2 5780.2 5714.9 5719.7 5721.5 5729.5 5730.7 5622.9 5814.9 5826.9 5841.2 5858.4 5654.6 5673.6 5679.6 5686.2 5690.6 03:43:59 INFO - PROCESS | 21960 | 03:43:59 INFO - PROCESS | 21960 | [#5] yui.html Cycles:180 Average:23292.94 Median:22478.22 stddev:33678.48 (149.8%) stddev-sans-first:33730.63 03:43:59 INFO - PROCESS | 21960 | Values: 804.3 822.7 890.2 893.9 896.3 16439.3 17542.3 17564.0 17570.6 17571.6 16201.3 16203.7 16362.9 16385.7 16388.2 722.2 849.0 871.8 875.0 878.8 878.8 879.5 880.8 881.3 881.7 932.6 933.0 934.1 935.1 935.3 753.3 898.6 921.4 925.2 930.7 679.7 682.1 682.7 683.2 683.4 14448.3 14482.5 14532.2 14544.3 14571.5 14767.3 15003.8 15022.4 15168.2 15698.0 14205.5 14380.2 14413.6 14582.1 14611.7 4604.7 4638.7 4689.7 4748.4 4790.3 87677.1 88626.0 89179.0 89243.0 89254.0 12585.4 12652.8 12689.7 12756.4 12928.4 22462.9 22474.8 22481.7 22526.2 22546.9 9062.3 9165.0 9348.7 9463.0 9467.3 4962.0 5104.7 5128.6 5133.3 5265.0 3503.5 3607.7 3611.9 3619.7 3622.6 585.3 680.4 694.2 708.9 711.2 733.2 770.2 796.4 804.7 816.3 10099.9 10835.8 10918.6 10922.7 10996.1 610.8 700.4 765.1 769.0 779.6 5151.0 5299.0 5301.4 5315.2 5339.3 122628.0 125851.0 129488.4 132203.3 133546.0 106203.5 108365.5 115227.4 117903.0 118066.8 76920.2 100780.5 101707.5 103439.0 104768.5 10073.2 10092.9 10180.9 10256.6 10324.2 50764.0 52516.2 56171.0 59687.0 61075.0 75603.0 78284.6 78385.6 78826.6 79858.6 14617.2 15511.2 15605.7 15715.5 16022.8 17320.9 17354.9 18615.2 19181.5 19360.7 15138.5 15218.2 15746.6 15841.3 16085.9 15666.6 15690.5 15828.5 15863.2 15917.2 15696.0 15814.1 15855.3 16039.5 16858.7 12466.3 12467.3 12497.9 13424.0 13473.8 12054.5 12837.6 12895.2 12947.4 13097.3 03:43:59 INFO - PROCESS | 21960 | -------- Summary: end -------- 03:43:59 INFO - PROCESS | 21960 | 03:44:00 INFO - TEST-INFO | 21960: exit 0 03:44:00 INFO - TEST-OK | dromaeo_css | took 1112686ms 03:44:00 INFO - TEST-START | kraken 03:44:00 INFO - operating with platform_type : linux_ 03:44:00 INFO - Initialising browser for kraken test... 03:44:00 INFO - TEST-INFO | started process 22160 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1ZeHZV/profile http://localhost:59820/getInfo.html) 03:44:04 INFO - PROCESS | 22160 | __metrics Screen width/height:1600/1200 03:44:04 INFO - PROCESS | 22160 | colorDepth:24 03:44:04 INFO - PROCESS | 22160 | Browser inner width/height: 1024/697 03:44:04 INFO - PROCESS | 22160 | __metrics 03:44:04 INFO - PROCESS | 22160 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:44:04 INFO - PROCESS | 22160 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:05 INFO - TEST-INFO | 22160: exit 0 03:44:05 INFO - Browser initialized. 03:44:05 INFO - Running cycle 1/1 for kraken test... 03:44:05 INFO - TEST-INFO | started process 22245 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1ZeHZV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:17 INFO - PROCESS | 22245 | RSS: Main: 145022976 03:44:17 INFO - PROCESS | 22245 | 03:44:19 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-beat-detection) 03:44:20 INFO - PROCESS | 22245 | RSS: Main: 191434752 03:44:20 INFO - PROCESS | 22245 | 03:44:23 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-dft) 03:44:23 INFO - PROCESS | 22245 | RSS: Main: 181825536 03:44:23 INFO - PROCESS | 22245 | 03:44:26 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-fft) 03:44:27 INFO - PROCESS | 22245 | RSS: Main: 269447168 03:44:27 INFO - PROCESS | 22245 | 03:44:29 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59820/tests/kraken/driver.html?testName=audio-oscillator) 03:44:30 INFO - PROCESS | 22245 | RSS: Main: 175493120 03:44:30 INFO - PROCESS | 22245 | 03:44:32 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59820/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:44:32 INFO - PROCESS | 22245 | RSS: Main: 177352704 03:44:32 INFO - PROCESS | 22245 | 03:44:35 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59820/tests/kraken/driver.html?testName=imaging-darkroom) 03:44:36 INFO - PROCESS | 22245 | RSS: Main: 213118976 03:44:36 INFO - PROCESS | 22245 | 03:44:39 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59820/tests/kraken/driver.html?testName=imaging-desaturate) 03:44:40 INFO - PROCESS | 22245 | RSS: Main: 212029440 03:44:40 INFO - PROCESS | 22245 | 03:44:43 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59820/tests/kraken/driver.html?testName=json-parse-financial) 03:44:43 INFO - PROCESS | 22245 | RSS: Main: 211959808 03:44:43 INFO - PROCESS | 22245 | 03:44:45 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59820/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:44:45 INFO - PROCESS | 22245 | RSS: Main: 175570944 03:44:45 INFO - PROCESS | 22245 | 03:44:47 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:44:48 INFO - PROCESS | 22245 | RSS: Main: 181874688 03:44:48 INFO - PROCESS | 22245 | 03:44:50 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:44:50 INFO - PROCESS | 22245 | RSS: Main: 186408960 03:44:50 INFO - PROCESS | 22245 | 03:44:53 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:44:53 INFO - PROCESS | 22245 | RSS: Main: 181043200 03:44:53 INFO - PROCESS | 22245 | 03:44:56 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:44:57 INFO - PROCESS | 22245 | RSS: Main: 184410112 03:44:57 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | Cycle 1(1): loaded http://localhost:59820/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59820/tests/kraken/driver.html?testName=ai-astar) 03:44:58 INFO - PROCESS | 22245 | RSS: Main: 235376640 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | __start_tp_report 03:44:58 INFO - PROCESS | 22245 | _x_x_mozilla_page_load 03:44:58 INFO - PROCESS | 22245 | _x_x_mozilla_page_load_details 03:44:58 INFO - PROCESS | 22245 | |i|pagename|runs| 03:44:58 INFO - PROCESS | 22245 | |0;ai-astar;101;111;109;109;112;110;110;111;111;111 03:44:58 INFO - PROCESS | 22245 | |1;audio-beat-detection;150;120;126;176;121;121;148;116;155;114 03:44:58 INFO - PROCESS | 22245 | |2;audio-dft;161;161;160;161;160;160;161;160;161;161 03:44:58 INFO - PROCESS | 22245 | |3;audio-fft;76;75;76;110;76;75;74;73;112;74 03:44:58 INFO - PROCESS | 22245 | |4;audio-oscillator;82;83;115;84;83;83;118;83;84;133 03:44:58 INFO - PROCESS | 22245 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;116 03:44:58 INFO - PROCESS | 22245 | |6;imaging-darkroom;146;145;146;145;146;145;146;146;145;146 03:44:58 INFO - PROCESS | 22245 | |7;imaging-desaturate;95;95;95;95;95;94;95;94;95;95 03:44:58 INFO - PROCESS | 22245 | |8;json-parse-financial;50;50;51;50;50;52;52;49;50;49 03:44:58 INFO - PROCESS | 22245 | |9;json-stringify-tinderbox;60;61;59;59;60;58;59;59;59;59 03:44:58 INFO - PROCESS | 22245 | |10;stanford-crypto-aes;73;72;72;76;72;73;73;72;72;75 03:44:58 INFO - PROCESS | 22245 | |11;stanford-crypto-ccm;119;118;127;119;116;115;115;129;162;123 03:44:58 INFO - PROCESS | 22245 | |12;stanford-crypto-pbkdf2;150;157;156;155;155;152;153;161;157;152 03:44:58 INFO - PROCESS | 22245 | |13;stanford-crypto-sha256-iterative;62;62;61;61;63;62;60;62;62;60 03:44:58 INFO - PROCESS | 22245 | __end_tp_report 03:44:58 INFO - PROCESS | 22245 | __start_cc_report 03:44:58 INFO - PROCESS | 22245 | _x_x_mozilla_cycle_collect,1661 03:44:58 INFO - PROCESS | 22245 | __end_cc_report 03:44:58 INFO - PROCESS | 22245 | __startTimestamp1472899498983__endTimestamp 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | ------- Summary: start ------- 03:44:58 INFO - PROCESS | 22245 | Number of tests: 14 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 03:44:58 INFO - PROCESS | 22245 | Values: 101.0 111.0 109.0 109.0 112.0 110.0 110.0 111.0 111.0 111.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:137.00 stddev:21.01 (15.3%) stddev-sans-first:21.55 03:44:58 INFO - PROCESS | 22245 | Values: 150.0 120.0 126.0 176.0 121.0 121.0 148.0 116.0 155.0 114.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 03:44:58 INFO - PROCESS | 22245 | Values: 161.0 161.0 160.0 161.0 160.0 160.0 161.0 160.0 161.0 161.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:15.27 (20.4%) stddev-sans-first:16.04 03:44:58 INFO - PROCESS | 22245 | Values: 76.0 75.0 76.0 110.0 76.0 75.0 74.0 73.0 112.0 74.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:83.00 stddev:19.32 (23.3%) stddev-sans-first:19.93 03:44:58 INFO - PROCESS | 22245 | Values: 82.0 83.0 115.0 84.0 83.0 83.0 118.0 83.0 84.0 133.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:44:58 INFO - PROCESS | 22245 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:44:58 INFO - PROCESS | 22245 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 145.0 146.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:44:58 INFO - PROCESS | 22245 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:1.06 (2.1%) stddev-sans-first:1.12 03:44:58 INFO - PROCESS | 22245 | Values: 50.0 50.0 51.0 50.0 50.0 52.0 52.0 49.0 50.0 49.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.83 03:44:58 INFO - PROCESS | 22245 | Values: 60.0 61.0 59.0 59.0 60.0 58.0 59.0 59.0 59.0 59.0 03:44:58 INFO - PROCESS | 22245 | 03:44:58 INFO - PROCESS | 22245 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:1.41 (1.9%) stddev-sans-first:1.50 03:44:58 INFO - PROCESS | 22245 | Values: 73.0 72.0 72.0 76.0 72.0 73.0 73.0 72.0 72.0 75.0 03:44:59 INFO - PROCESS | 22245 | 03:44:59 INFO - PROCESS | 22245 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:121.00 stddev:14.10 (11.7%) stddev-sans-first:14.83 03:44:59 INFO - PROCESS | 22245 | Values: 119.0 118.0 127.0 119.0 116.0 115.0 115.0 129.0 162.0 123.0 03:44:59 INFO - PROCESS | 22245 | 03:44:59 INFO - PROCESS | 22245 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.80 Median:155.50 stddev:3.19 (2.1%) stddev-sans-first:2.87 03:44:59 INFO - PROCESS | 22245 | Values: 150.0 157.0 156.0 155.0 155.0 152.0 153.0 161.0 157.0 152.0 03:44:59 INFO - PROCESS | 22245 | 03:44:59 INFO - PROCESS | 22245 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 03:44:59 INFO - PROCESS | 22245 | Values: 62.0 62.0 61.0 61.0 63.0 62.0 60.0 62.0 62.0 60.0 03:44:59 INFO - PROCESS | 22245 | -------- Summary: end -------- 03:44:59 INFO - PROCESS | 22245 | 03:44:59 INFO - TEST-INFO | 22245: exit 0 03:44:59 INFO - TEST-OK | kraken | took 59460ms 03:44:59 INFO - SUITE-END | took 1172s 03:45:00 INFO - Completed test suite (00:19:32) 03:45:00 INFO - javascript benchmark 03:45:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.2616398228406, 3230.806151630901, 3232.3050544133007, 3253.4469140246156, 3275.1157187559343, 5625.437456254378, 5702.344230413499, 5709.2292540507015, 5749.0226661467495, 5752.309722833255, 5658.43415658434, 5713.942860571393, 5717.914231286523, 5719.513841323482, 5725.971370143144, 4992.450830408652, 5086.84739457815, 5136.306598609177, 5150.948490515094, 5204.1673332266655, 5175.767090480937, 5188.533032027117, 5214.243934629477, 5222.981518603874, 5228.980348831979, 5120.411152717429, 5181.0674078665825, 5189.143791274435, 5198.298229738974, 5222.268882356454, 5268.8155914543195, 5273.314469119009, 5275.841724748264, 5293.973530132324, 5308.628396012281, 4938.037082768861, 5057.0897238497055, 5117.488251174853, 5178.948210517884, 5182.481751824825, 5578.135389014709, 5594.636348637326, 5653.321601407816, 5664.575156863251, 5679.290088738908, 6090.025595904678, 6119.785807496761, 6130.785422510191, 6132.601380910227, 6134.294556126058, 5907.113932910055, 6044.002739548013, 6088.786892458743, 6101.725422356005, 6167.537434692416, 5742.167385729088, 5756.107803290497, 5763.6541807491685, 5768.538516918637, 5780.104083867007, 6159.6905237824885, 6213.5339849511465, 6224.502039836802, 6232.719527621268, 6233.0, 5848.327442344185, 5972.343042265347, 6035.7283922223605, 6039.909401358983, 6107.908381374283, 6168.290646575701, 6169.722362493699, 6169.81490555284, 6170.876582468415, 6177.0, 6122.2959359674205, 6128.846778830476, 6131.785387511416, 6132.0, 6141.048137538689, 5830.446107619854, 5997.55018373615, 6051.0, 6068.089786531968, 6095.207623009069, 4553.089382123589, 4572.7942242599165, 4587.541245875385, 4612.60792832613, 4617.607503362252, 4159.688023398257, 4169.520505141947, 4172.457580514514, 4172.624463798212, 4204.285271503852, 5576.191452239484, 5752.194692743019, 5793.7392817323325, 5794.362620111784, 5858.355580886099, 6174.938250617436, 6188.504919606421, 6190.102435146879, 6190.350013248544, 6191.1641928339095, 5833.766649334064, 5834.970825145847, 5836.358000619928, 5839.708014599253, 5851.444112809277, 5088.491150884882, 5223.033738758342, 5252.789888404497, 5272.288241087481, 5338.679679219259, 6314.605511344606, 6328.905066423915, 6334.144890439732, 6352.587081839665, 6358.1416508771645, 6125.84685382869, 6132.233470816148, 6135.601185922901, 6139.785107521216, 6143.8326717923455, 5998.70006499682, 6188.1027251049245, 6202.41077097675, 6227.532935029982, 6238.407351301619, 5973.412846316312, 5980.641161530322, 5985.072313791369, 6014.0678194879865, 6038.365971572922, 6136.447719705248, 6145.508359331332, 6146.969265153645, 6149.2620885494025, 6153.107799369156, 5809.8547536311935, 5910.763569457174, 5964.165016897725, 5975.820725378246, 6063.242094738158, 5936.1689363488285, 5995.310539288038, 6002.759889604542, 6004.639721616717, 6007.12896629995, 5843.328017278068, 5856.238688970577, 5888.352281248974, 5910.083936989773, 5972.223610930551, 5808.215890854681, 6009.489193418433, 6050.5764596479585, 6079.209702738615, 6086.391360863879, 5866.560007999503, 5881.088431293157, 5883.117532370007, 5884.205632239593, 5884.735186916513], "value": 5618.848561794398, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.5491890919628, 624.2664868779185, 665.1120753793685, 667.9732810687572, 669.180254188619, 34522.48216276755, 46741.0, 47509.76245118769, 47601.0, 47678.52321476784, 49718.50281497184, 50017.49982500174, 50153.49846501534, 50165.24752128712, 50236.0, 8521.36089793268, 9230.815383692323, 9373.156415922565, 9395.0, 10995.780084398348, 10851.077658399006, 11247.381394023325, 11255.155863310243, 11267.436628168558, 11270.267432616894, 11321.0, 11352.262102963281, 11396.088312934986, 11405.600803971862, 11406.71483212918, 11150.665480035612, 11152.721181970435, 11154.0, 11202.887971120266, 11216.383098929597, 11172.720681983015, 11246.718832029264, 11247.044001259903, 11249.60626378081, 11253.662390128227, 46100.3084953726, 46428.30357544639, 46513.302300465184, 46515.0, 46532.0, 3684.5394325709285, 3878.747881387729, 3911.706622003361, 4158.59241510204, 4316.136772645452, 35562.46656300157, 35612.64387356119, 35639.82180089109, 36105.0, 36356.454653180226, 4638.46657634373, 4645.814167433298, 4683.906321873577, 4686.625069994427, 4723.149833030053, 386974.0, 391436.0, 392417.0, 392501.0, 393324.0, 38192.23615527674, 63151.0, 63214.0, 63602.681986590695, 63737.681311594075, 40014.0, 41860.79069604693, 42003.36994945078, 42020.789896050934, 42044.78977605092, 14806.851931480764, 15068.849311506963, 15103.0, 15132.243387830564, 15950.680986380208, 10723.731906702238, 11857.229280096939, 12057.035437165006, 12104.273743575413, 12188.0, 9308.44149351041, 9322.487263200454, 9324.766880827898, 9383.249340052917, 9389.859152112724, 3522.647735226457, 3577.266660334625, 3607.1162565171703, 3609.1157666371905, 3648.9635103648625, 1093.3002878157986, 1124.8256520239377, 1129.48043899807, 1129.5538262386312, 1209.5766481731323, 34703.30593388169, 35265.0, 35764.0, 35957.820210898775, 36718.449223261676, 1075.381655548063, 1087.521490544158, 1090.9890901091046, 1106.8284415915384, 1134.0813940708053, 17385.130743462778, 18109.909450452928, 18121.728174077398, 18217.544561385806, 18264.726029109574, 616744.0, 619804.0, 620164.0, 623301.0, 625548.0, 63035.0, 63276.0, 63635.681821590595, 63644.68177659082, 63816.68091659512, 1368.261138984937, 1455.046944251517, 1456.788765628978, 1482.4811316039516, 1521.3078049487456, 34054.0, 36487.604739087816, 36561.63438365582, 36627.0, 36706.63293367032, 54896.176557350875, 55709.442905570424, 56129.43870561242, 56150.71924640514, 56265.15602265888, 454935.0, 457407.0, 457611.0, 458091.0, 458998.0, 29920.25199370033, 31622.209444764067, 31984.52023219607, 33403.83298083494, 33621.495677564366, 54417.18374224469, 54619.72690136524, 54978.45021549733, 54978.725106374215, 55409.1688624679, 44091.338629919934, 44363.140393842536, 44887.55112448834, 46581.76709116433, 46800.297995530775, 45158.0, 45560.0, 46398.76800615976, 46615.0, 47450.76274618605, 45886.0, 46094.0, 46537.0, 47288.763556182, 47376.05247894954, 37534.6246537542, 43416.0, 43539.78230108829, 43648.18897323606, 43848.56151438445, 29175.562366564944, 30013.049151805062, 30931.84534077315, 31483.527747083353, 31980.840095799373], "value": 20966.77459484194, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.6551632837593, 412.39378114172155, 416.566770558619, 417.4552209366776, 435.4730775761329, 15678.921605391972, 20090.296839610615, 20385.28651497198, 20631.793682063177, 22367.993440295224, 21097.15611375547, 21198.89400552995, 21341.893290533564, 21536.246231381905, 21544.13823447064, 5534.806281780128, 5849.736761845727, 5861.062230043194, 5869.50109240715, 5887.470127688507, 4376.321670141133, 4568.725876447424, 5781.13283007548, 5847.590668653196, 5948.613340132899, 6573.112629794989, 6590.209174898995, 6710.0, 6720.932790672079, 6724.125863637744, 6490.870182596345, 6698.832529186785, 6704.329567043256, 6718.025886246492, 6755.088063111466, 5888.116782482619, 5970.044792833126, 5979.760809567612, 5981.970090149565, 5991.880162396771, 25555.977760889542, 25640.358991025187, 25647.48705025889, 25734.356641083934, 25754.48491030169, 2682.785377169822, 2706.0393560286207, 2751.257160566656, 2805.9158225253277, 3184.570083038783, 15293.158876261801, 15578.610534736723, 15685.843141568552, 16250.51248462548, 16346.75479867803, 2063.484128967758, 2134.1890081768984, 2136.59404713104, 2143.442704896738, 2195.9560808783895, 55576.65601829334, 61864.38135618631, 64901.0, 69649.0, 85020.57489712512, 10517.211209159344, 11538.250013749079, 11571.76856462866, 11579.594714185132, 11699.0, 15909.443169489012, 16054.598635034217, 16274.0, 16280.674386512203, 16294.837051629333, 8417.28453081495, 8426.915730842737, 8498.617562209716, 8545.487270763773, 8684.088170742107, 8019.15798841125, 8126.512409255463, 8154.69524876017, 8302.584870756438, 8360.958195208985, 6917.377436030782, 7052.612106334201, 7059.0, 7114.64426778659, 7154.676384868816, 2004.849636277285, 2008.8348757720487, 2035.9389218323474, 2079.563291708728, 2079.854410191302, 1008.0927165551062, 1031.8400647899587, 1061.2147011211803, 1079.3362082319318, 1110.228391268061, 21429.142834286453, 21575.0, 21775.649345805596, 21815.765857334973, 21967.391684529928, 1072.4316112460408, 1078.320657985464, 1091.0671375973586, 1096.5065720425841, 1109.2623405435456, 11098.0, 12567.245965242115, 12703.237805731686, 12724.491020359266, 12813.743725125632, 529774.0, 536393.0, 548408.0, 558536.0, 564612.0, 83928.0, 87869.56065219762, 89395.55302223447, 89653.55173224091, 89848.5507572471, 1070.5931745936493, 1076.3004047369116, 1078.1105587890022, 1081.2809481694742, 1088.21648413144, 18961.95709236005, 19184.71222931685, 19302.7104593434, 19362.515937101685, 19687.40937771812, 43788.34317485177, 43889.34165987449, 43894.56105438905, 45073.54926450693, 47068.29397559108, 60137.39862601493, 61572.0, 62440.0, 63771.0, 71170.64414677894, 8937.776555586162, 9171.312151588523, 9294.488803115622, 9498.19265362452, 9516.476593787409, 13393.933030334785, 14147.0, 14331.713365732836, 14700.705985880437, 14839.40642374293, 12200.51197952072, 12607.558735444216, 12641.0, 12662.0, 12721.173123746927, 11908.761824763284, 12093.637190884288, 12229.205101668364, 12551.811722823983, 12639.304838233986, 12402.13185077036, 12627.494900203888, 12628.810567841307, 12745.235285882876, 12840.55058072963, 9146.58840352199, 9244.583993720164, 9250.398724082914, 9454.905450945402, 9545.665901693408, 12222.511099555919, 12374.62876113718, 12642.747145057232, 12674.366281685694, 12831.615051548468], "value": 10048.415013237201, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.7520046391658, 1468.8384277729451, 1560.2978659603177, 1719.1060648462799, 1882.7646544181978, 9223.30825188111, 9627.807443851127, 9702.223822094224, 9949.054839790226, 9951.154151897097, 9779.804403911918, 9842.60629574816, 9892.554835032422, 9893.505324733771, 9899.950500247507, 2583.470388570348, 2621.488809682113, 2622.921312360623, 2624.3832699315644, 2626.146502386722, 2496.2636022373445, 2563.6410902473576, 2566.178822776703, 2569.1264969910226, 2572.0, 2515.5094756522485, 2521.596544552872, 2548.324693956103, 2548.9745102548923, 2555.0, 2457.410221546834, 2480.08236952327, 2487.8507289562685, 2499.550080985422, 2506.736792636765, 2453.914113006036, 2480.7643273888953, 2494.6382774497597, 2510.5104504621604, 2519.10571747029, 9297.72106836796, 9694.660686875926, 9804.166645835112, 9839.360441571274, 9890.9505452473, 5680.914786278209, 5830.970845145747, 5877.5591830612875, 5886.470217680429, 5897.705114744289, 3658.981705091484, 3663.1757854482794, 3705.314516814398, 3731.5149030625844, 3739.5138631977943, 1218.8476440444945, 1229.6987238126626, 1237.22055105284, 1266.6643339515035, 1275.349571718421, 14007.0, 14032.0, 14096.577102686935, 14100.85899141016, 14119.717605647933, 4814.614830813596, 4857.562819346205, 4952.727599981963, 4990.326305948651, 5037.546620804145, 10411.271211015308, 10425.530851111718, 10435.32170408921, 10452.843207351896, 10473.266871319087, 5791.913121303184, 5801.042827933344, 5807.68057756819, 5809.506191973645, 5818.359980402152, 4620.768961551909, 4737.081481999727, 4835.637327200474, 5203.947960520346, 5409.621326507186, 4157.729747566398, 4172.269852775752, 4239.660827133822, 4264.147170565837, 4280.807363668642, 1220.4385982447996, 1246.4827096754927, 1276.0365923727527, 1278.1308710077237, 1288.323630094208, 1038.5482315192914, 1046.2676126711333, 1049.7742985258205, 1055.794120146564, 1067.066316972649, 6521.152250207538, 6533.0, 6625.370589793962, 6744.83137921546, 6823.69293381799, 494.1081348166522, 504.53078636868065, 987.0474991633108, 991.7272749993809, 1018.9643362482426, 4418.668599855024, 4565.0, 4568.817247310137, 4584.312353147055, 4627.791749371287, 11829.88170118305, 12154.149209555422, 12170.208936419105, 12420.192687475284, 12502.3748812559, 12139.757204855854, 12370.381480925918, 12482.251064936132, 12567.057470689551, 12608.432620532098, 9018.819623607491, 9049.954750226207, 9172.44965302084, 9183.0, 9258.027907069662, 6279.937200627935, 6437.25971513282, 6500.154979852589, 6536.640484773383, 6596.076549283008, 11164.832527511931, 11414.543418263176, 11429.485673144894, 11571.826422603835, 11674.94162529182, 10210.846837297595, 10381.688549343531, 11676.241044332091, 11790.882091178979, 11811.586594469152, 7046.401055910305, 7961.323287520626, 7965.681372745257, 8198.467099638505, 8212.507249565046, 8912.0, 9040.819183616422, 9110.590023449093, 9135.954320228357, 9149.176574108096, 8623.525706086226, 8626.353023523136, 8648.091950345122, 8734.388592798443, 8821.514816685143, 8385.496164229571, 8491.617877195418, 8555.61499732526, 8627.654893804178, 8735.650573977224, 7821.530708157528, 7937.682492700227, 7948.205179482005, 7950.443468957349, 7951.920480795349, 5686.872220278748, 5789.797357092481, 5829.358770535323, 5846.269216347957, 5939.613925094855, 5608.6073974823, 5671.206031155724, 5736.799212027559, 5779.133130030529, 5952.196453478899], "value": 5039.041839052089, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.09196548800554, 358.15475477872235, 363.25713915043735, 369.7993863114743, 371.0519622364857, 5582.776688932443, 5602.831915042555, 5605.971970140154, 5622.662640241589, 5645.689487078209, 5243.610443232536, 5300.966311569246, 5442.537384322337, 5510.610974250837, 5538.9169162462495, 4341.761203133827, 4346.5436129206455, 4357.891052723691, 4396.538363471837, 4406.647468202552, 4344.023469321452, 4356.869293921188, 4368.97815510922, 4394.274944634148, 4405.471343438792, 4167.979160104195, 4280.4221430107, 4288.270993931023, 4306.363581838897, 4366.97816510917, 4275.315949448089, 4283.892902677442, 4297.0, 4298.699091063627, 4307.870763877088, 3795.411711184771, 3835.2137811748526, 3842.1739326045035, 3846.2115266370606, 3847.5575308839284, 5227.947720522822, 5307.761150748225, 5329.0, 5417.322834645669, 5433.918491222635, 4922.507749225085, 4926.531979461945, 4944.9258261126115, 4949.133901567247, 4955.4548999610015, 5704.258446401983, 5731.541476681856, 5732.398098199671, 5733.655980641175, 5735.0, 4098.897527561805, 4161.708680392374, 4163.437935878648, 4215.409842622035, 4223.87328380146, 5914.97042514789, 5948.196993405922, 5956.404359564052, 5964.0, 5973.790917317917, 4291.206126866539, 4292.678049146327, 4317.395564620955, 4318.265894797892, 4329.134173165379, 4167.416561681367, 4373.125374925028, 4378.693491455568, 4381.079973205598, 4384.846530371422, 4901.877453063631, 4911.901761964741, 4922.778474968635, 4929.162042452792, 4967.392119137409, 5778.104393818965, 5819.76720931158, 5845.210896528914, 5849.2103566018895, 5854.385289544536, 1456.3664805809506, 1501.78974943508, 1506.0662389318693, 1507.2313120308502, 1508.2911031815029, 943.7829299261208, 965.9565319560637, 971.5385192033727, 979.7060881735594, 986.4870267461024, 4965.652404331662, 4974.975125124423, 4995.850124496198, 5005.749712514433, 5025.924611130836, 5190.780166660832, 5216.60875434344, 5216.7395377888815, 5219.712915789592, 5259.815906443256, 4834.516548345137, 4937.901241975141, 4998.125328067574, 4999.275394909783, 5014.674046186986, 2771.708970558103, 2785.701796018293, 2811.6204312417563, 2812.5078111330436, 2814.4520513717375, 5362.65142765719, 5363.873586546791, 5379.246905433242, 5388.245645609618, 5392.73036348181, 4959.8840260941, 5274.314339135888, 5308.593222795962, 5322.0154271459905, 5337.450595354124, 5397.596624877481, 5401.432849550741, 5429.484199001167, 5432.945670543323, 5449.509544141047, 5197.818076367309, 5246.711430871339, 5266.0257852297445, 5278.287431196739, 5296.470352964673, 5251.083354575476, 5526.751296191762, 5862.6256366171765, 5891.231121372948, 5911.467967882739, 6100.298465676504, 6153.784617538365, 6176.567640265138, 6213.291344880014, 6226.159468471698, 4304.677149213759, 4398.868033958986, 4472.865814025585, 4476.306172543261, 4480.39514665529, 5851.736671849862, 5853.131965337039, 5869.6771677558145, 5892.263467066617, 5913.438223368773, 5732.426757324234, 5751.763370875282, 5758.942410575841, 5815.9709201453725, 5825.446582574648, 5654.491095801398, 5720.971395142998, 5740.052891272893, 5740.225069615549, 5780.2485676863425, 5714.857128571819, 5719.714014299185, 5721.5136713379825, 5729.4843464088435, 5730.713464326684, 5622.88754224905, 5814.924239015796, 5826.854328641818, 5841.18223448726, 5858.443447872445, 5654.635491882324, 5673.574481913791, 5679.630823996427, 5686.2323586315315, 5690.630109042899], "value": 4314.452397951118, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.2681160144269, 822.6873787960578, 890.1899271662788, 893.9240164586009, 896.3143195455474, 16439.2602332895, 17542.298308067693, 17564.0, 17570.560735981606, 17571.648567028653, 16201.270942807572, 16203.675926481463, 16362.918185409057, 16385.67228655426, 16388.180590970464, 722.1911459165729, 848.9915100848974, 871.790770215147, 875.0287181228831, 878.7978764884081, 878.8374150782093, 879.5206612396228, 880.8018195905934, 881.2509367037999, 881.7487016200384, 932.5943214701626, 933.0296491648677, 934.1265916368184, 935.0555938502093, 935.3078721745927, 753.2693287511133, 898.6135961536504, 921.4195057114042, 925.22281283722, 930.7393929699695, 679.6839469646588, 682.0723815610766, 682.6757290287073, 683.2484267305973, 683.3986092238848, 14448.277586120757, 14482.49311274111, 14532.200728959902, 14544.27278636064, 14571.48999785013, 14767.335469903772, 15003.774943375858, 15022.399104035825, 15168.24158792056, 15698.0, 14205.502807401692, 14380.209088500129, 14413.639659008504, 14582.125072495684, 14611.707765844623, 4604.746738929391, 4638.721676699409, 4689.671722979393, 4748.38271024768, 4790.257510085942, 87677.12322876754, 88625.99999999936, 89179.0, 89243.0, 89254.0, 12585.433655485525, 12652.81020784689, 12689.746205075848, 12756.425960831784, 12928.418221180069, 22462.887685561793, 22474.77525224727, 22481.662775058387, 22526.21158259453, 22546.887265563568, 9062.320325975577, 9165.0, 9348.672796452092, 9462.952685236529, 9467.337286390024, 4961.975190124026, 5104.744762761847, 5128.589712822965, 5133.332666753298, 5265.0259701955265, 3503.491993660905, 3607.6933460655614, 3611.94582081269, 3619.6561326673923, 3622.601513833476, 585.28887401807, 680.425040840489, 694.2224708326706, 708.8830342993451, 711.1892442615423, 733.2154594583744, 770.1989930472358, 796.4026979765176, 804.6821505505293, 816.2775943290231, 10099.899001010042, 10835.83746243807, 10918.563257469612, 10922.672319830259, 10996.0653344465, 610.7770663707714, 700.3976580141075, 765.1239330966013, 769.0, 779.6296759039411, 5150.97424512875, 5299.0, 5301.390340110936, 5315.176147697113, 5339.252504649352, 122628.0, 125851.0, 129488.35255823661, 132203.33898330448, 133546.0, 106203.46898265614, 108365.4581727102, 115227.42386288183, 117903.0, 118066.8193318073, 76920.23079769242, 100780.49609751905, 101707.49146254221, 103438.96561034293, 104768.47615761873, 10073.221861246075, 10092.949535252277, 10180.949095254477, 10256.641017564349, 10324.22568307395, 50764.0, 52516.212256815415, 56171.0, 59687.0, 61075.0, 75603.0, 78284.60857695674, 78385.60807195927, 78826.6058669703, 79858.60070699609, 14617.19605421712, 15511.224438778241, 15605.687886242438, 15715.507026832433, 16022.839771602135, 17320.91339543294, 17354.913225433793, 18615.162317695464, 19181.520461988002, 19360.70958935645, 15138.545843624708, 15218.239088045775, 15746.606334841721, 15841.287142078403, 16085.91957040254, 15666.608334791721, 15690.450834220748, 15828.52514424569, 15863.206839658202, 15917.204139792733, 15696.0, 15814.051156930622, 15855.286512107214, 16039.518814435585, 16858.662826743643, 12466.283511581669, 12467.314297713712, 12497.93751031239, 13424.0, 13473.797893031417, 12054.457549410474, 12837.550685725955, 12895.16181448203, 12947.352632368533, 13097.279649618982], "value": 8376.480418731795, "unit": "score"}], "name": "dromaeo_css", "value": 7743.593093617662, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 109.0, 109.0, 112.0, 110.0, 110.0, 111.0, 111.0, 111.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 126.0, 176.0, 121.0, 121.0, 148.0, 116.0, 155.0, 114.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 110.0, 76.0, 75.0, 74.0, 73.0, 112.0, 74.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 115.0, 84.0, 83.0, 83.0, 118.0, 83.0, 84.0, 133.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 50.0, 52.0, 52.0, 49.0, 50.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 59.0, 60.0, 58.0, 59.0, 59.0, 59.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 72.0, 76.0, 72.0, 73.0, 73.0, 72.0, 72.0, 75.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 127.0, 119.0, 116.0, 115.0, 115.0, 129.0, 162.0, 123.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 157.0, 156.0, 155.0, 155.0, 152.0, 153.0, 161.0, 157.0, 152.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 63.0, 62.0, 60.0, 62.0, 62.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1462.0999999999997, "alertThreshold": 2.0}]} 03:45:00 INFO - Return code: 0 03:45:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:45:00 INFO - # TBPL SUCCESS # 03:45:00 INFO - Running post-action listener: _resource_record_post_action 03:45:00 INFO - [mozharness: 2016-09-03 10:45:00.290320Z] Finished run-tests step (success) 03:45:00 INFO - Running post-run listener: _resource_record_post_run 03:45:00 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331141120; Read time: 8; Write time: 265116 03:45:00 INFO - TinderboxPrint: CPU usage
13.0% 03:45:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 03:45:00 INFO - TinderboxPrint: I/O write bytes / time
331,141,120 / 265,116 03:45:00 INFO - TinderboxPrint: CPU idle
8,257.8 (87.0%) 03:45:00 INFO - TinderboxPrint: CPU user
1,189.1 (12.5%) 03:45:00 INFO - TinderboxPrint: Swap in / out
0 / 0 03:45:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78823424; Read time: 0; Write time: 104052 03:45:00 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 251703296; Read time: 0; Write time: 161060 03:45:00 INFO - Running post-run listener: _upload_blobber_files 03:45:00 INFO - Blob upload gear active. 03:45:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:45:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:45:01 INFO - (blobuploader) - INFO - Open directory for files ... 03:45:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:45:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:07 INFO - (blobuploader) - INFO - Done attempting. 03:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:08 INFO - (blobuploader) - INFO - Done attempting. 03:45:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:45:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:45:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:09 INFO - (blobuploader) - INFO - Done attempting. 03:45:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:45:09 INFO - Return code: 0 03:45:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:45:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:45:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ca775be4891e54ebba8957581179afb961f259532f136dd1ed308561fb4a6d6abee0010d815f528682c72eeba20d86a598fb4c0182dd789f45eeeffb7968f29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf42542241b81c3c45b341f8757f8bd272f47104f3021c19c1404977e78201bb94422de3964d55282998f9c7b9e75ae314331e9f3525c95b6c3b405fccb6509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6150dfd52c6415a9fdcfe889e4a023948286f9bdce1dbd4d901696c6c54d61e871f25a05bb3080540a7d5ec12737fec9a7f55195449906ab3ced8a0c736c864c"} 03:45:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:45:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:45:09 INFO - Contents: 03:45:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ca775be4891e54ebba8957581179afb961f259532f136dd1ed308561fb4a6d6abee0010d815f528682c72eeba20d86a598fb4c0182dd789f45eeeffb7968f29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf42542241b81c3c45b341f8757f8bd272f47104f3021c19c1404977e78201bb94422de3964d55282998f9c7b9e75ae314331e9f3525c95b6c3b405fccb6509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6150dfd52c6415a9fdcfe889e4a023948286f9bdce1dbd4d901696c6c54d61e871f25a05bb3080540a7d5ec12737fec9a7f55195449906ab3ced8a0c736c864c"} 03:45:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:45:09 INFO - Copying logs to upload dir... 03:45:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:45:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.291970 ========= master_lag: -0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-03 03:45:06.978820) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:06.985069) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ca775be4891e54ebba8957581179afb961f259532f136dd1ed308561fb4a6d6abee0010d815f528682c72eeba20d86a598fb4c0182dd789f45eeeffb7968f29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf42542241b81c3c45b341f8757f8bd272f47104f3021c19c1404977e78201bb94422de3964d55282998f9c7b9e75ae314331e9f3525c95b6c3b405fccb6509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6150dfd52c6415a9fdcfe889e4a023948286f9bdce1dbd4d901696c6c54d61e871f25a05bb3080540a7d5ec12737fec9a7f55195449906ab3ced8a0c736c864c"} build_url:https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ca775be4891e54ebba8957581179afb961f259532f136dd1ed308561fb4a6d6abee0010d815f528682c72eeba20d86a598fb4c0182dd789f45eeeffb7968f29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/acf42542241b81c3c45b341f8757f8bd272f47104f3021c19c1404977e78201bb94422de3964d55282998f9c7b9e75ae314331e9f3525c95b6c3b405fccb6509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6150dfd52c6415a9fdcfe889e4a023948286f9bdce1dbd4d901696c6c54d61e871f25a05bb3080540a7d5ec12737fec9a7f55195449906ab3ced8a0c736c864c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:07.013377) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:07.013732) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1472897828.276629-302157299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004372 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:45:07.094494) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 03:45:07.097860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 03:45:07.098164) ========= ========= Total master_lag: 0.14 =========