builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1476466312.79 results: success (0) buildid: 20161014072435 builduid: 7e1fd950349947fb832cb9892e31249f revision: 6d65311378a748b84f98ede85d2c541addd8f266 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.794249) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.794740) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.795020) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.809620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.809987) ========= 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=db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-14 10:31:53-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.2M=0s 2016-10-14 10:31:53 (70.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107280 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.930914) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.931225) ========= 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=db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017432 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.980641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:52.981024) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6d65311378a748b84f98ede85d2c541addd8f266 --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-beta --rev 6d65311378a748b84f98ede85d2c541addd8f266 --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=db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-14 10:31:53,273 truncating revision to first 12 chars 2016-10-14 10:31:53,273 Setting DEBUG logging. 2016-10-14 10:31:53,273 attempt 1/10 2016-10-14 10:31:53,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6d65311378a7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 10:31:53,585 unpacking tar archive at: mozilla-beta-6d65311378a7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.713125 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:53.707415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:53.707762) ========= script_repo_revision: 6d65311378a748b84f98ede85d2c541addd8f266 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:53.708144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:53.708404) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 10:31:53.716586) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-14 10:31:53.716880) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:31:54 INFO - MultiFileLogger online at 20161014 10:31:54 in /builds/slave/test 10:31:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:31:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:31:54 INFO - {'append_to_log': False, 10:31:54 INFO - 'base_work_dir': '/builds/slave/test', 10:31:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:31:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:31:54 INFO - 'branch': 'Mozilla-Beta', 10:31:54 INFO - 'buildbot_json_path': 'buildprops.json', 10:31:54 INFO - 'config_files': ('talos/linux_config.py',), 10:31:54 INFO - 'default_actions': ('clobber', 10:31:54 INFO - 'read-buildbot-config', 10:31:54 INFO - 'download-and-extract', 10:31:54 INFO - 'populate-webroot', 10:31:54 INFO - 'create-virtualenv', 10:31:54 INFO - 'install', 10:31:54 INFO - 'run-tests'), 10:31:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:31:54 INFO - 'download_minidump_stackwalk': True, 10:31:54 INFO - 'download_symbols': 'ondemand', 10:31:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:31:54 INFO - 'tooltool.py': '/tools/tooltool.py', 10:31:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:31:54 INFO - '/tools/misc-python/virtualenv.py')}, 10:31:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:31:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:31:54 INFO - 'installer_path': 'installer.exe', 10:31:54 INFO - 'log_level': 'info', 10:31:54 INFO - 'log_name': 'talos', 10:31:54 INFO - 'log_to_console': True, 10:31:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:31:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:31:54 INFO - 'opt_config_files': (), 10:31:54 INFO - 'pip_index': False, 10:31:54 INFO - 'sps_profile': False, 10:31:54 INFO - 'sps_profile_interval': 0, 10:31:54 INFO - 'suite': 'dromaeojs', 10:31:54 INFO - 'system_bits': '32', 10:31:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:31:54 INFO - 'title': 'talos-linux64-ix-031', 10:31:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:31:54 INFO - 'use_talos_json': True, 10:31:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:31:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:31:54 INFO - 'work_dir': 'build'} 10:31:54 INFO - [mozharness: 2016-10-14 17:31:54.097631Z] Running clobber step. 10:31:54 INFO - Running pre-action listener: _resource_record_pre_action 10:31:54 INFO - Running main action method: clobber 10:31:54 INFO - rmtree: /builds/slave/test/build 10:31:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:31:54 INFO - Running post-action listener: _resource_record_post_action 10:31:54 INFO - [mozharness: 2016-10-14 17:31:54.605106Z] Finished clobber step (success) 10:31:54 INFO - [mozharness: 2016-10-14 17:31:54.605207Z] Running read-buildbot-config step. 10:31:54 INFO - Running pre-action listener: _resource_record_pre_action 10:31:54 INFO - Running main action method: read_buildbot_config 10:31:54 INFO - Using buildbot properties: 10:31:54 INFO - { 10:31:54 INFO - "project": "", 10:31:54 INFO - "product": "firefox", 10:31:54 INFO - "script_repo_revision": "production", 10:31:54 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:31:54 INFO - "repository": "", 10:31:54 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:31:54 INFO - "buildid": "20161014072435", 10:31:54 INFO - "pgo_build": "True", 10:31:54 INFO - "basedir": "/builds/slave/test", 10:31:54 INFO - "buildnumber": 282, 10:31:54 INFO - "slavename": "talos-linux64-ix-031", 10:31:54 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:31:54 INFO - "platform": "ubuntu64_hw", 10:31:54 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:31:54 INFO - "branch": "mozilla-beta", 10:31:54 INFO - "repo_path": "releases/mozilla-beta", 10:31:54 INFO - "revision": "6d65311378a748b84f98ede85d2c541addd8f266", 10:31:54 INFO - "stage_platform": "linux64-pgo", 10:31:54 INFO - "builduid": "7e1fd950349947fb832cb9892e31249f", 10:31:54 INFO - "slavebuilddir": "test" 10:31:54 INFO - } 10:31:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 10:31:54 INFO - Running post-action listener: _resource_record_post_action 10:31:54 INFO - [mozharness: 2016-10-14 17:31:54.607637Z] Finished read-buildbot-config step (success) 10:31:54 INFO - [mozharness: 2016-10-14 17:31:54.607727Z] Running download-and-extract step. 10:31:54 INFO - Running pre-action listener: _resource_record_pre_action 10:31:54 INFO - Running main action method: download_and_extract 10:31:54 INFO - mkdir: /builds/slave/test/build/tests 10:31: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')]} 10:31:54 INFO - https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:31:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:31:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:31:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:31:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:31:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:31:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:31:54 INFO - retry: Failed, sleeping 30 seconds before retrying 10:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 10:32:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:32:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:32:24 INFO - retry: Failed, sleeping 60 seconds before retrying 10:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 10:33:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:33:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:33:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 10:33:24 INFO - Caught exception: HTTP Error 404: Not Found 10:33:24 INFO - Caught exception: HTTP Error 404: Not Found 10:33:24 INFO - Caught exception: HTTP Error 404: Not Found 10:33:24 INFO - trying https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:33:24 INFO - Downloading https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:33:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:33:25 INFO - Downloaded 1327 bytes. 10:33:25 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:33:25 INFO - Using the following test package requirements: 10:33:25 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:33:25 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:33:25 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 10:33:25 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'jsshell-linux-x86_64.zip'], 10:33:25 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:33:25 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:33:25 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 10:33:25 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 10:33:25 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:33:25 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:33:25 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:33:25 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:33:25 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')]} 10:33:25 INFO - https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:33:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:33:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:33:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:33:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:33:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:33:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:33:25 INFO - retry: Failed, sleeping 30 seconds before retrying 10:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 10:33:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:33:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:33:55 INFO - retry: Failed, sleeping 60 seconds before retrying 10:34:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 10:34:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:34:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 10:34:55 INFO - Caught exception: HTTP Error 404: Not Found 10:34:55 INFO - Caught exception: HTTP Error 404: Not Found 10:34:55 INFO - Caught exception: HTTP Error 404: Not Found 10:34:55 INFO - trying https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:55 INFO - Downloading https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:34:56 INFO - Downloaded 21773321 bytes. 10:34:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:34:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:34:57 INFO - Return code: 0 10:34:57 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:34: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')]} 10:34:57 INFO - https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:34:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:34:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:34:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:34:57 INFO - retry: Failed, sleeping 30 seconds before retrying 10:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 10:35:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:35:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:35:27 INFO - retry: Failed, sleeping 60 seconds before retrying 10:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 10:36:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:36:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:36:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 10:36:27 INFO - Caught exception: HTTP Error 404: Not Found 10:36:27 INFO - Caught exception: HTTP Error 404: Not Found 10:36:27 INFO - Caught exception: HTTP Error 404: Not Found 10:36:27 INFO - trying https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:36:27 INFO - Downloading https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:36:28 INFO - Downloaded 21773321 bytes. 10:36:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:36:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:36:30 INFO - Return code: 0 10:36:30 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')]} 10:36:30 INFO - https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:36:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:36:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:36:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:36:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:36:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:36:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:36:30 INFO - retry: Failed, sleeping 30 seconds before retrying 10:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 10:37:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:37:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:37:00 INFO - retry: Failed, sleeping 60 seconds before retrying 10:38:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 10:38:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:38:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:38:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 10:38:00 INFO - Caught exception: HTTP Error 404: Not Found 10:38:00 INFO - Caught exception: HTTP Error 404: Not Found 10:38:00 INFO - Caught exception: HTTP Error 404: Not Found 10:38:00 INFO - trying https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:38:00 INFO - Downloading https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:38:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:38:00 INFO - Downloaded 14413762 bytes. 10:38:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:38:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 10:38:01 INFO - Return code: 0 10:38:01 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')]} 10:38:01 INFO - https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:38:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:38:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:38:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:38:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:38:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:38:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:38:01 INFO - retry: Failed, sleeping 30 seconds before retrying 10:38:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 10:38:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:38:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:38:31 INFO - retry: Failed, sleeping 60 seconds before retrying 10:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 10:39:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:39:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:39:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 10:39:31 INFO - Caught exception: HTTP Error 404: Not Found 10:39:31 INFO - Caught exception: HTTP Error 404: Not Found 10:39:31 INFO - Caught exception: HTTP Error 404: Not Found 10:39:31 INFO - trying https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:39:31 INFO - Downloading https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:39:34 INFO - Downloaded 56776997 bytes. 10:39:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:39:34 INFO - mkdir: /builds/slave/test/properties 10:39:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:39:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:39:34 INFO - Contents: 10:39:34 INFO - build_url:https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:39:35 INFO - Running post-action listener: _resource_record_post_action 10:39:35 INFO - Running post-action listener: set_extra_try_arguments 10:39:35 INFO - [mozharness: 2016-10-14 17:39:35.031276Z] Finished download-and-extract step (success) 10:39:35 INFO - [mozharness: 2016-10-14 17:39:35.031510Z] Running populate-webroot step. 10:39:35 INFO - Running pre-action listener: _resource_record_pre_action 10:39:35 INFO - Running main action method: populate_webroot 10:39:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:39:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:39:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:39:35 INFO - '--tppagecycles', 10:39:35 INFO - '7'], 10:39:35 INFO - 'tests': ['tsvgm']}}, 10:39:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:39:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:39:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:39:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:39:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:39:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:39:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['damp', 'tps']}, 10:39:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'tests': ['damp', 'tps']}, 10:39:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['dromaeo_dom']}, 10:39:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:39:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['basic_compositor_video']}, 10:39:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:39:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['a11yr', 10:39:35 INFO - 'ts_paint', 10:39:35 INFO - 'tpaint', 10:39:35 INFO - 'sessionrestore', 10:39:35 INFO - 'sessionrestore_no_auto_restore', 10:39:35 INFO - 'tabpaint']}, 10:39:35 INFO - 'other-e10s': {'tests': ['a11yr', 10:39:35 INFO - 'ts_paint', 10:39:35 INFO - 'tpaint', 10:39:35 INFO - 'sessionrestore', 10:39:35 INFO - 'sessionrestore_no_auto_restore', 10:39:35 INFO - 'tabpaint']}, 10:39:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:39:35 INFO - 'ts_paint', 10:39:35 INFO - 'tpaint', 10:39:35 INFO - 'sessionrestore', 10:39:35 INFO - 'sessionrestore_no_auto_restore', 10:39:35 INFO - 'tabpaint']}, 10:39:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:39:35 INFO - 'ts_paint', 10:39:35 INFO - 'tpaint', 10:39:35 INFO - 'sessionrestore', 10:39:35 INFO - 'sessionrestore_no_auto_restore', 10:39:35 INFO - 'tabpaint']}, 10:39:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['a11yr', 10:39:35 INFO - 'ts_paint', 10:39:35 INFO - 'tpaint', 10:39:35 INFO - 'sessionrestore', 10:39:35 INFO - 'sessionrestore_no_auto_restore', 10:39:35 INFO - 'tabpaint']}, 10:39:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['a11yr', 10:39:35 INFO - 'ts_paint', 10:39:35 INFO - 'tpaint', 10:39:35 INFO - 'sessionrestore', 10:39:35 INFO - 'sessionrestore_no_auto_restore', 10:39:35 INFO - 'tabpaint']}, 10:39:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['tsvgx', 10:39:35 INFO - 'tsvgr_opacity', 10:39:35 INFO - 'tart', 10:39:35 INFO - 'tscrollx', 10:39:35 INFO - 'cart']}, 10:39:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:39:35 INFO - 'tsvgr_opacity', 10:39:35 INFO - 'tart', 10:39:35 INFO - 'tscrollx', 10:39:35 INFO - 'cart']}, 10:39:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'talos_options': ['--disable-e10s'], 10:39:35 INFO - 'tests': ['tp5o']}, 10:39:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'tests': ['tp5o']}, 10:39:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'talos_options': ['--disable-e10s', 10:39:35 INFO - '--xperf_path', 10:39:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:39:35 INFO - 'tests': ['tp5n']}, 10:39:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:35 INFO - 'talos_options': ['--xperf_path', 10:39:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:39:35 INFO - 'tests': ['tp5n']}}, 10:39:35 INFO - 'talos.zip': {'path': '', 10:39:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:39:35 INFO - Running post-action listener: _resource_record_post_action 10:39:35 INFO - [mozharness: 2016-10-14 17:39:35.050564Z] Finished populate-webroot step (success) 10:39:35 INFO - [mozharness: 2016-10-14 17:39:35.050654Z] Running create-virtualenv step. 10:39:35 INFO - Running pre-action listener: _resource_record_pre_action 10:39:35 INFO - Running main action method: create_virtualenv 10:39:35 INFO - Creating virtualenv /builds/slave/test/build/venv 10:39:35 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 10:39:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:39:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:39:35 INFO - Using real prefix '/usr' 10:39:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:39:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:39:37 INFO - Installing pip.................done. 10:39:37 INFO - Return code: 0 10:39:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:39:37 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')]} 10:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:37 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')]} 10:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:37 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:37 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 10:39:37 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 10:39:37 INFO - Using env: {'DISPLAY': ':0', 10:39:37 INFO - 'HOME': '/home/cltbld', 10:39:37 INFO - 'LANG': 'en_US.UTF-8', 10:39:37 INFO - 'LANGUAGE': 'en_US:en', 10:39:37 INFO - 'LOGNAME': 'cltbld', 10:39:37 INFO - 'MAIL': '/var/mail/cltbld', 10:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:37 INFO - 'MOZ_NO_REMOTE': '1', 10:39:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:37 INFO - 'NO_EM_RESTART': '1', 10:39:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:37 INFO - 'PWD': '/builds/slave/test', 10:39:37 INFO - 'SHELL': '/bin/bash', 10:39:37 INFO - 'SHLVL': '1', 10:39:37 INFO - 'TERM': 'linux', 10:39:37 INFO - 'TMOUT': '86400', 10:39:37 INFO - 'USER': 'cltbld', 10:39:37 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:37 INFO - '_': '/tools/buildbot/bin/python'} 10:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:37 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 10:39:37 INFO - Cleaning up... 10:39:37 INFO - Return code: 0 10:39:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:39:37 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')]} 10:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:37 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')]} 10:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:37 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:37 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 10:39:37 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 10:39:37 INFO - Using env: {'DISPLAY': ':0', 10:39:37 INFO - 'HOME': '/home/cltbld', 10:39:37 INFO - 'LANG': 'en_US.UTF-8', 10:39:37 INFO - 'LANGUAGE': 'en_US:en', 10:39:37 INFO - 'LOGNAME': 'cltbld', 10:39:37 INFO - 'MAIL': '/var/mail/cltbld', 10:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:37 INFO - 'MOZ_NO_REMOTE': '1', 10:39:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:37 INFO - 'NO_EM_RESTART': '1', 10:39:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:37 INFO - 'PWD': '/builds/slave/test', 10:39:37 INFO - 'SHELL': '/bin/bash', 10:39:37 INFO - 'SHLVL': '1', 10:39:37 INFO - 'TERM': 'linux', 10:39:37 INFO - 'TMOUT': '86400', 10:39:37 INFO - 'USER': 'cltbld', 10:39:37 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:37 INFO - '_': '/tools/buildbot/bin/python'} 10:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:38 INFO - Downloading/unpacking psutil>=3.1.1 10:39:38 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 10:39:38 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 10:39:38 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 10:39:38 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 10:39:38 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 10:39:38 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 10:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:39:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:39:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:39:40 INFO - Installing collected packages: psutil 10:39:40 INFO - Running setup.py install for psutil 10:39:40 INFO - building 'psutil._psutil_linux' extension 10:39:40 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 10:39:40 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 10:39:40 INFO - building 'psutil._psutil_posix' extension 10:39:40 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 10:39:40 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 10:39:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:39:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:39:40 INFO - Successfully installed psutil 10:39:40 INFO - Cleaning up... 10:39:40 INFO - Return code: 0 10:39:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:39:40 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')]} 10:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:40 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')]} 10:39:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:40 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:40 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 10:39:40 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 10:39:40 INFO - Using env: {'DISPLAY': ':0', 10:39:40 INFO - 'HOME': '/home/cltbld', 10:39:40 INFO - 'LANG': 'en_US.UTF-8', 10:39:40 INFO - 'LANGUAGE': 'en_US:en', 10:39:40 INFO - 'LOGNAME': 'cltbld', 10:39:40 INFO - 'MAIL': '/var/mail/cltbld', 10:39:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:40 INFO - 'MOZ_NO_REMOTE': '1', 10:39:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:40 INFO - 'NO_EM_RESTART': '1', 10:39:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:40 INFO - 'PWD': '/builds/slave/test', 10:39:40 INFO - 'SHELL': '/bin/bash', 10:39:40 INFO - 'SHLVL': '1', 10:39:40 INFO - 'TERM': 'linux', 10:39:40 INFO - 'TMOUT': '86400', 10:39:40 INFO - 'USER': 'cltbld', 10:39:40 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:40 INFO - '_': '/tools/buildbot/bin/python'} 10:39:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:39:40 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 10:39:40 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 10:39:40 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 10:39:40 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 10:39:40 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 10:39:40 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 10:39:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:39:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:39:42 INFO - Installing collected packages: mozsystemmonitor 10:39:42 INFO - Running setup.py install for mozsystemmonitor 10:39:42 INFO - Successfully installed mozsystemmonitor 10:39:42 INFO - Cleaning up... 10:39:42 INFO - Return code: 0 10:39:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:39:42 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')]} 10:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:42 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')]} 10:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:42 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:42 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 10:39:42 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 10:39:42 INFO - Using env: {'DISPLAY': ':0', 10:39:42 INFO - 'HOME': '/home/cltbld', 10:39:42 INFO - 'LANG': 'en_US.UTF-8', 10:39:42 INFO - 'LANGUAGE': 'en_US:en', 10:39:42 INFO - 'LOGNAME': 'cltbld', 10:39:42 INFO - 'MAIL': '/var/mail/cltbld', 10:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:42 INFO - 'MOZ_NO_REMOTE': '1', 10:39:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:42 INFO - 'NO_EM_RESTART': '1', 10:39:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:42 INFO - 'PWD': '/builds/slave/test', 10:39:42 INFO - 'SHELL': '/bin/bash', 10:39:42 INFO - 'SHLVL': '1', 10:39:42 INFO - 'TERM': 'linux', 10:39:42 INFO - 'TMOUT': '86400', 10:39:42 INFO - 'USER': 'cltbld', 10:39:42 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:42 INFO - '_': '/tools/buildbot/bin/python'} 10:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:43 INFO - Downloading/unpacking jsonschema==2.5.1 10:39:43 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 10:39:43 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 10:39:43 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 10:39:43 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 10:39:43 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 10:39:43 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 10:39:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:39:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:39:45 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 10:39:45 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 10:39:45 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 10:39:45 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 10:39:45 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 10:39:45 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 10:39:45 INFO - Downloading functools32-3.2.3-2.tar.gz 10:39:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:39:45 INFO - warning: no files found matching '*.txt' 10:39:45 INFO - no previously-included directories found matching 'build' 10:39:45 INFO - no previously-included directories found matching 'dist' 10:39:45 INFO - no previously-included directories found matching '.git*' 10:39:45 INFO - Installing collected packages: jsonschema, functools32 10:39:45 INFO - Running setup.py install for functools32 10:39:45 INFO - warning: no files found matching '*.txt' 10:39:45 INFO - no previously-included directories found matching 'build' 10:39:45 INFO - no previously-included directories found matching 'dist' 10:39:45 INFO - no previously-included directories found matching '.git*' 10:39:45 INFO - Successfully installed jsonschema functools32 10:39:45 INFO - Cleaning up... 10:39:45 INFO - Return code: 0 10:39:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:39: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')]} 10:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39: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')]} 10:39:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:45 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:45 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 10:39:45 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 10:39:45 INFO - Using env: {'DISPLAY': ':0', 10:39:45 INFO - 'HOME': '/home/cltbld', 10:39:45 INFO - 'LANG': 'en_US.UTF-8', 10:39:45 INFO - 'LANGUAGE': 'en_US:en', 10:39:45 INFO - 'LOGNAME': 'cltbld', 10:39:45 INFO - 'MAIL': '/var/mail/cltbld', 10:39:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:45 INFO - 'MOZ_NO_REMOTE': '1', 10:39:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:45 INFO - 'NO_EM_RESTART': '1', 10:39:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:45 INFO - 'PWD': '/builds/slave/test', 10:39:45 INFO - 'SHELL': '/bin/bash', 10:39:45 INFO - 'SHLVL': '1', 10:39:45 INFO - 'TERM': 'linux', 10:39:45 INFO - 'TMOUT': '86400', 10:39:45 INFO - 'USER': 'cltbld', 10:39:45 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:45 INFO - '_': '/tools/buildbot/bin/python'} 10:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:39:45 INFO - Cleaning up... 10:39:46 INFO - Return code: 0 10:39:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:39:46 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')]} 10:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:46 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')]} 10:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:46 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:46 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 10:39:46 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 10:39:46 INFO - Using env: {'DISPLAY': ':0', 10:39:46 INFO - 'HOME': '/home/cltbld', 10:39:46 INFO - 'LANG': 'en_US.UTF-8', 10:39:46 INFO - 'LANGUAGE': 'en_US:en', 10:39:46 INFO - 'LOGNAME': 'cltbld', 10:39:46 INFO - 'MAIL': '/var/mail/cltbld', 10:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:46 INFO - 'MOZ_NO_REMOTE': '1', 10:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:46 INFO - 'NO_EM_RESTART': '1', 10:39:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:46 INFO - 'PWD': '/builds/slave/test', 10:39:46 INFO - 'SHELL': '/bin/bash', 10:39:46 INFO - 'SHLVL': '1', 10:39:46 INFO - 'TERM': 'linux', 10:39:46 INFO - 'TMOUT': '86400', 10:39:46 INFO - 'USER': 'cltbld', 10:39:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:46 INFO - '_': '/tools/buildbot/bin/python'} 10:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:46 INFO - Downloading/unpacking blobuploader==1.2.4 10:39:46 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 10:39:46 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 10:39:46 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 10:39:46 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 10:39:46 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 10:39:46 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 10:39:48 INFO - Downloading blobuploader-1.2.4.tar.gz 10:39:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:39:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:39:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 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 10:39:48 INFO - Downloading docopt-0.6.1.tar.gz 10:39:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:39:48 INFO - Installing collected packages: blobuploader, requests, docopt 10:39:48 INFO - Running setup.py install for blobuploader 10:39:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:39:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:39:49 INFO - Running setup.py install for requests 10:39:49 INFO - Running setup.py install for docopt 10:39:49 INFO - Successfully installed blobuploader requests docopt 10:39:49 INFO - Cleaning up... 10:39:49 INFO - Return code: 0 10:39:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:49 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')]} 10:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:49 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')]} 10:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:49 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:49 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 10:39:49 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 10:39:49 INFO - Using env: {'DISPLAY': ':0', 10:39:49 INFO - 'HOME': '/home/cltbld', 10:39:49 INFO - 'LANG': 'en_US.UTF-8', 10:39:49 INFO - 'LANGUAGE': 'en_US:en', 10:39:49 INFO - 'LOGNAME': 'cltbld', 10:39:49 INFO - 'MAIL': '/var/mail/cltbld', 10:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:49 INFO - 'MOZ_NO_REMOTE': '1', 10:39:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:49 INFO - 'NO_EM_RESTART': '1', 10:39:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:49 INFO - 'PWD': '/builds/slave/test', 10:39:49 INFO - 'SHELL': '/bin/bash', 10:39:49 INFO - 'SHLVL': '1', 10:39:49 INFO - 'TERM': 'linux', 10:39:49 INFO - 'TMOUT': '86400', 10:39:49 INFO - 'USER': 'cltbld', 10:39:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:49 INFO - '_': '/tools/buildbot/bin/python'} 10:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:39:49 INFO - Running setup.py (path:/tmp/pip-uUfcI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:39:49 INFO - Running setup.py (path:/tmp/pip-t2b8Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:39:49 INFO - Running setup.py (path:/tmp/pip-ZU2KBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:39:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:39:49 INFO - Running setup.py (path:/tmp/pip-0U1rWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:39:50 INFO - Running setup.py (path:/tmp/pip-Oc6D5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:39:50 INFO - Running setup.py (path:/tmp/pip-0rgVtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:39:50 INFO - Running setup.py (path:/tmp/pip-vnHtof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:39:50 INFO - Running setup.py (path:/tmp/pip-kCJYnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:39:50 INFO - Running setup.py (path:/tmp/pip-LnE0ZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:39:50 INFO - Running setup.py (path:/tmp/pip-LdNV6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:39:50 INFO - Running setup.py (path:/tmp/pip-AR5EX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:39:50 INFO - Running setup.py (path:/tmp/pip-ECLZT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:39:50 INFO - Running setup.py (path:/tmp/pip-PFwe7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:39:50 INFO - Running setup.py (path:/tmp/pip-LME78O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:50 INFO - Running setup.py (path:/tmp/pip-SU3_4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:39:50 INFO - Running setup.py (path:/tmp/pip-MlQfTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:39:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:39:50 INFO - Running setup.py (path:/tmp/pip-HCa7SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:39:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:39:50 INFO - Running setup.py install for manifestparser 10:39:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Running setup.py install for mozcrash 10:39:51 INFO - Running setup.py install for mozdebug 10:39:51 INFO - Running setup.py install for mozdevice 10:39:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Running setup.py install for mozfile 10:39:51 INFO - Running setup.py install for mozhttpd 10:39:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Running setup.py install for mozinfo 10:39:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Running setup.py install for mozInstall 10:39:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Running setup.py install for mozleak 10:39:51 INFO - Running setup.py install for mozlog 10:39:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:39:51 INFO - Running setup.py install for moznetwork 10:39:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:39:52 INFO - Running setup.py install for mozprocess 10:39:52 INFO - Running setup.py install for mozprofile 10:39:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:39:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:39:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:39:52 INFO - Running setup.py install for mozrunner 10:39:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:39:52 INFO - Running setup.py install for mozscreenshot 10:39:52 INFO - Running setup.py install for moztest 10:39:52 INFO - Running setup.py install for mozversion 10:39:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:39:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:39:52 INFO - Cleaning up... 10:39:52 INFO - Return code: 0 10:39:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39: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')]} 10:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39: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')]} 10:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:52 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:52 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 10:39:52 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 10:39:52 INFO - Using env: {'DISPLAY': ':0', 10:39:52 INFO - 'HOME': '/home/cltbld', 10:39:52 INFO - 'LANG': 'en_US.UTF-8', 10:39:52 INFO - 'LANGUAGE': 'en_US:en', 10:39:52 INFO - 'LOGNAME': 'cltbld', 10:39:52 INFO - 'MAIL': '/var/mail/cltbld', 10:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:52 INFO - 'MOZ_NO_REMOTE': '1', 10:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:52 INFO - 'NO_EM_RESTART': '1', 10:39:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:52 INFO - 'PWD': '/builds/slave/test', 10:39:52 INFO - 'SHELL': '/bin/bash', 10:39:52 INFO - 'SHLVL': '1', 10:39:52 INFO - 'TERM': 'linux', 10:39:52 INFO - 'TMOUT': '86400', 10:39:52 INFO - 'USER': 'cltbld', 10:39:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:52 INFO - '_': '/tools/buildbot/bin/python'} 10:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:39:52 INFO - Running setup.py (path:/tmp/pip-kG0ZHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:39:53 INFO - Running setup.py (path:/tmp/pip-LNmOYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:39:53 INFO - Running setup.py (path:/tmp/pip-lXIx_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:39:53 INFO - Running setup.py (path:/tmp/pip-uM1ApQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:39:53 INFO - Running setup.py (path:/tmp/pip-04W2ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:39:53 INFO - Running setup.py (path:/tmp/pip-vKYgfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:39:53 INFO - Running setup.py (path:/tmp/pip-TWfEFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:39:53 INFO - Running setup.py (path:/tmp/pip-0U6uoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:39:53 INFO - Running setup.py (path:/tmp/pip-AK7svl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:39:53 INFO - Running setup.py (path:/tmp/pip-9SGCP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:39:53 INFO - Running setup.py (path:/tmp/pip-bzOYrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:39:53 INFO - Running setup.py (path:/tmp/pip-PjsAu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:39:53 INFO - Running setup.py (path:/tmp/pip-tLiG5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:39:53 INFO - Running setup.py (path:/tmp/pip-DLjp0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:39:53 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)) 10:39:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:53 INFO - Running setup.py (path:/tmp/pip-Ga_jE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:54 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)) 10:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:39:54 INFO - Running setup.py (path:/tmp/pip-MurQFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:39:54 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)) 10:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:39:54 INFO - Running setup.py (path:/tmp/pip-Iu4w6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:39:54 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)) 10:39:54 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)) 10:39:54 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)) 10:39:54 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)) 10:39:54 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)) 10:39:54 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)) 10:39:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:39:54 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 10:39:54 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 10:39:54 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 10:39:54 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 10:39:54 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 10:39:54 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 10:39:56 INFO - Downloading blessings-1.6.tar.gz 10:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:39:56 INFO - Installing collected packages: blessings 10:39:56 INFO - Running setup.py install for blessings 10:39:56 INFO - Successfully installed blessings 10:39:56 INFO - Cleaning up... 10:39:56 INFO - Return code: 0 10:39:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:39:56 INFO - Reading from file tmpfile_stdout 10:39:56 INFO - Current package versions: 10:39:56 INFO - argparse == 1.2.1 10:39:56 INFO - blessings == 1.6 10:39:56 INFO - blobuploader == 1.2.4 10:39:56 INFO - docopt == 0.6.1 10:39:56 INFO - functools32 == 3.2.3-2 10:39:56 INFO - jsonschema == 2.5.1 10:39:56 INFO - manifestparser == 1.1 10:39:56 INFO - mozInstall == 1.12 10:39:56 INFO - mozcrash == 0.17 10:39:56 INFO - mozdebug == 0.1 10:39:56 INFO - mozdevice == 0.48 10:39:56 INFO - mozfile == 1.2 10:39:56 INFO - mozhttpd == 0.7 10:39:56 INFO - mozinfo == 0.9 10:39:56 INFO - mozleak == 0.1 10:39:56 INFO - mozlog == 3.3 10:39:56 INFO - moznetwork == 0.27 10:39:56 INFO - mozprocess == 0.23 10:39:56 INFO - mozprofile == 0.28 10:39:56 INFO - mozrunner == 6.12 10:39:56 INFO - mozscreenshot == 0.1 10:39:56 INFO - mozsystemmonitor == 0.3 10:39:56 INFO - moztest == 0.7 10:39:56 INFO - mozversion == 1.4 10:39:56 INFO - psutil == 3.1.1 10:39:56 INFO - requests == 1.2.3 10:39:56 INFO - wsgiref == 0.1.2 10:39:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:56 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')]} 10:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:56 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')]} 10:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:56 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:56 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 10:39:56 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 10:39:56 INFO - Using env: {'DISPLAY': ':0', 10:39:56 INFO - 'HOME': '/home/cltbld', 10:39:56 INFO - 'LANG': 'en_US.UTF-8', 10:39:56 INFO - 'LANGUAGE': 'en_US:en', 10:39:56 INFO - 'LOGNAME': 'cltbld', 10:39:56 INFO - 'MAIL': '/var/mail/cltbld', 10:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:56 INFO - 'MOZ_NO_REMOTE': '1', 10:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:56 INFO - 'NO_EM_RESTART': '1', 10:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:56 INFO - 'PWD': '/builds/slave/test', 10:39:56 INFO - 'SHELL': '/bin/bash', 10:39:56 INFO - 'SHLVL': '1', 10:39:56 INFO - 'TERM': 'linux', 10:39:56 INFO - 'TMOUT': '86400', 10:39:56 INFO - 'USER': 'cltbld', 10:39:56 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:56 INFO - '_': '/tools/buildbot/bin/python'} 10:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 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)) 10:39:56 INFO - Cleaning up... 10:39:56 INFO - Return code: 0 10:39:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:39:56 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')]} 10:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:56 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')]} 10:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:56 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 0x199a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1afd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c5b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, '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': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', '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 10:39:56 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 10:39:56 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 10:39:56 INFO - Using env: {'DISPLAY': ':0', 10:39:56 INFO - 'HOME': '/home/cltbld', 10:39:56 INFO - 'LANG': 'en_US.UTF-8', 10:39:56 INFO - 'LANGUAGE': 'en_US:en', 10:39:56 INFO - 'LOGNAME': 'cltbld', 10:39:56 INFO - 'MAIL': '/var/mail/cltbld', 10:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:56 INFO - 'MOZ_NO_REMOTE': '1', 10:39:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:56 INFO - 'NO_EM_RESTART': '1', 10:39:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:56 INFO - 'PWD': '/builds/slave/test', 10:39:56 INFO - 'SHELL': '/bin/bash', 10:39:56 INFO - 'SHLVL': '1', 10:39:56 INFO - 'TERM': 'linux', 10:39:56 INFO - 'TMOUT': '86400', 10:39:56 INFO - 'USER': 'cltbld', 10:39:56 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:56 INFO - '_': '/tools/buildbot/bin/python'} 10:39:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:39:57 INFO - Cleaning up... 10:39:57 INFO - Return code: 0 10:39:57 INFO - Running post-action listener: _resource_record_post_action 10:39:57 INFO - Running post-action listener: _start_resource_monitoring 10:39:57 INFO - Starting resource monitoring. 10:39:57 INFO - [mozharness: 2016-10-14 17:39:57.065695Z] Finished create-virtualenv step (success) 10:39:57 INFO - [mozharness: 2016-10-14 17:39:57.066130Z] Running install step. 10:39:57 INFO - Running pre-action listener: _resource_record_pre_action 10:39:57 INFO - Running main action method: install 10:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:39:57 INFO - Reading from file tmpfile_stdout 10:39:57 INFO - Detecting whether we're running mozinstall >=1.0... 10:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:39:57 INFO - Reading from file tmpfile_stdout 10:39:57 INFO - Output received: 10:39:57 INFO - Usage: mozinstall [options] installer 10:39:57 INFO - Options: 10:39:57 INFO - -h, --help show this help message and exit 10:39:57 INFO - -d DEST, --destination=DEST 10:39:57 INFO - Directory to install application into. [default: 10:39:57 INFO - "/builds/slave/test"] 10:39:57 INFO - --app=APP Application being installed. [default: firefox] 10:39:57 INFO - mkdir: /builds/slave/test/build/application 10:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:40:11 INFO - Reading from file tmpfile_stdout 10:40:11 INFO - Output received: 10:40:11 INFO - /builds/slave/test/build/application/firefox/firefox 10:40:11 INFO - Running post-action listener: _resource_record_post_action 10:40:11 INFO - [mozharness: 2016-10-14 17:40:11.352026Z] Finished install step (success) 10:40:11 INFO - [mozharness: 2016-10-14 17:40:11.352314Z] Running run-tests step. 10:40:11 INFO - Running pre-action listener: _resource_record_pre_action 10:40:11 INFO - Running main action method: run_tests 10:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:40:11 INFO - Python 2.7.3 10:40:11 INFO - Return code: 0 10:40:11 INFO - grabbing minidump binary from tooltool 10:40:11 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')]} 10:40:11 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 0x1c593b0>, '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 0x1c5a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c5a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:40:11 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 10:40:11 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 10:40:11 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 10:40:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:40:11 INFO - Return code: 0 10:40:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:40:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:40:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:40:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:40:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 10:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 10:40:11 INFO - Using env: {'DISPLAY': ':0', 10:40:11 INFO - 'HOME': '/home/cltbld', 10:40:11 INFO - 'LANG': 'en_US.UTF-8', 10:40:11 INFO - 'LANGUAGE': 'en_US:en', 10:40:11 INFO - 'LOGNAME': 'cltbld', 10:40:11 INFO - 'MAIL': '/var/mail/cltbld', 10:40:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:40:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:40:11 INFO - 'MOZ_NO_REMOTE': '1', 10:40:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:40:11 INFO - 'NO_EM_RESTART': '1', 10:40:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:40:11 INFO - 'PWD': '/builds/slave/test', 10:40:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:40:11 INFO - 'SHELL': '/bin/bash', 10:40:11 INFO - 'SHLVL': '1', 10:40:11 INFO - 'TERM': 'linux', 10:40:11 INFO - 'TMOUT': '86400', 10:40:11 INFO - 'USER': 'cltbld', 10:40:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521', 10:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:40:11 INFO - '_': '/tools/buildbot/bin/python'} 10:40:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 10:40:11 INFO - mozversion application_buildid: 20161014072435 10:40:11 INFO - mozversion application_changeset: 6d65311378a748b84f98ede85d2c541addd8f266 10:40:11 INFO - mozversion application_display_name: Firefox 10:40:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:40:11 INFO - mozversion application_name: Firefox 10:40:11 INFO - mozversion application_remotingname: firefox 10:40:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:40:11 INFO - mozversion application_vendor: Mozilla 10:40:11 INFO - mozversion application_version: 50.0 10:40:11 INFO - mozversion platform_buildid: 20161014072435 10:40:11 INFO - mozversion platform_changeset: 6d65311378a748b84f98ede85d2c541addd8f266 10:40:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:40:11 INFO - mozversion platform_version: 50.0 10:40:11 INFO - using testdate: 1476466811 10:40:11 INFO - actual date: 1476466811 10:40:11 INFO - starting webserver on 'localhost:58159' 10:40:11 INFO - SUITE-START | Running 2 tests 10:40:11 INFO - TEST-START | dromaeo_css 10:40:11 INFO - operating with platform_type : linux_ 10:40:11 INFO - Initialising browser for dromaeo_css test... 10:40:11 INFO - TEST-INFO | started process 10428 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLmlEMD/profile http://localhost:58159/getInfo.html) 10:40:15 INFO - PROCESS | 10428 | __metrics Screen width/height:1600/1200 10:40:15 INFO - PROCESS | 10428 | colorDepth:24 10:40:15 INFO - PROCESS | 10428 | Browser inner width/height: 1024/697 10:40:15 INFO - PROCESS | 10428 | __metrics 10:40:16 INFO - PROCESS | 10428 | 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" 10:40:16 INFO - PROCESS | 10428 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:40:17 INFO - TEST-INFO | 10428: exit 0 10:40:17 INFO - Browser initialized. 10:40:17 INFO - Running cycle 1/1 for dromaeo_css test... 10:40:17 INFO - TEST-INFO | started process 10518 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLmlEMD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:29 INFO - PROCESS | 10518 | RSS: Main: 143986688 10:40:29 INFO - PROCESS | 10518 | 10:41:20 INFO - PROCESS | 10518 | 1476466880842 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] 10:43:28 INFO - PROCESS | 10518 | Cycle 1(1): loaded http://localhost:58159/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58159/tests/dromaeo/cssquery-ext.html) 10:43:29 INFO - PROCESS | 10518 | RSS: Main: 265392128 10:43:29 INFO - PROCESS | 10518 | 10:46:32 INFO - PROCESS | 10518 | Cycle 1(1): loaded http://localhost:58159/tests/dromaeo/cssquery-ext.html (next: http://localhost:58159/tests/dromaeo/cssquery-jquery.html) 10:46:33 INFO - PROCESS | 10518 | RSS: Main: 227823616 10:46:33 INFO - PROCESS | 10518 | 10:49:34 INFO - PROCESS | 10518 | Cycle 1(1): loaded http://localhost:58159/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58159/tests/dromaeo/cssquery-mootools.html) 10:49:35 INFO - PROCESS | 10518 | RSS: Main: 207867904 10:49:35 INFO - PROCESS | 10518 | 10:52:37 INFO - PROCESS | 10518 | Cycle 1(1): loaded http://localhost:58159/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58159/tests/dromaeo/cssquery-prototype.html) 10:52:38 INFO - PROCESS | 10518 | RSS: Main: 176025600 10:52:38 INFO - PROCESS | 10518 | 10:55:40 INFO - PROCESS | 10518 | Cycle 1(1): loaded http://localhost:58159/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58159/tests/dromaeo/cssquery-yui.html) 10:55:41 INFO - PROCESS | 10518 | RSS: Main: 177557504 10:55:41 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | Cycle 1(1): loaded http://localhost:58159/tests/dromaeo/cssquery-yui.html (next: http://localhost:58159/tests/dromaeo/cssquery-dojo.html) 10:58:43 INFO - PROCESS | 10518 | RSS: Main: 266457088 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | __start_tp_report 10:58:43 INFO - PROCESS | 10518 | _x_x_mozilla_page_load 10:58:43 INFO - PROCESS | 10518 | _x_x_mozilla_page_load_details 10:58:43 INFO - PROCESS | 10518 | |i|pagename|runs| 10:58:43 INFO - PROCESS | 10518 | |0;dojo.html;3188.2826364068083;3191.042687193842;3214.9839250803757;3240.2061494933746;3246.4318744219754;5560.833175004746;5648.152777083435;5657.151427285909;5657.9434205657935;5671.291088613918;5623.212750214963;5664.745086471108;5678.091505359143;5680.318361796591;5694.145878118285;5088.643794934347;5119.129747942859;5124;5179.8446046618665;5192.221166824968;5029.924551131718;5041.924371134436;5187.766550505217;5196.324477817879;5227.059129356714;5064.493550644925;5073.822416215433;5109.20807274873;5109.642325037249;5128.615353848476;5129.615278854082;5157.252198431244;5250.317458730341;5256.448072952361;5284.101702710548;4900.460949295611;5135.998480296306;5151.716655583941;5194.584433245332;5213.869653258661;5404.270423492818;5429.185622156708;5435.347758268994;5451.972740136313;5514.91727624086;6038.396160383971;6068.848278793065;6089.543284253698;6096.420840020191;6097;5862.824115276549;5940.405959404068;6133.754649814002;6134.141220229171;6143.815685529396;5567.248421463091;5608.49523542879;5628;5664.745086471077;5668.376478587352;6129.570930034944;6132.325444201134;6136.048912418582;6139.386061393914;6143.354947730424;5789.623674461231;5845.707714614252;5904.527637788967;5949.137375080676;5949.167116603679;5959;6050.184944516715;6079.787207447718;6082.057281121433;6095.4818840398175;6017.789377371771;6020.307664618537;6022.518198544106;6027.487663548647;6053.636781793107;5813.215215945793;5870.26621672291;5980.760769569256;6025.457708806229;6030;4593.035462552834;4594.816207351735;4597.126545956257;4659.487456379796;4683.227267500893;4111.958880411217;4141.109661422808;4142.5857414258935;4167.187398457332;4175.039740859619;5562.137868630369;5772.6536407815665;5781.624194427349;5783.16144159103;5783.652980821164;5977.282726072812;5988.970055149696;6042.728077236535;6095.359987201368;6131.417515336036;5741.827745167651;5748.885022299531;5752.07966725322;5752.827415177551;5758.596898217168;4999.42506611737;5112.872178195575;5114.616403769732;5124.948750512522;5129.025485157883;6133.365267548864;6199.721012554446;6204.3795620437595;6253.249610046823;6288.151099601587;6059.909101363395;6079.513638908788;6080.5095287853555;6082.422169893853;6122.081687746695;6106.6336019838955;6183.907241391473;6299.023651334051;6306.306306306211;6311.179546659088;5869.853253668522;5896.498797602252;5907.320658124198;5970.791022314198;6009.038553831366;6089.908651370321;6097.939020609914;6103.023516237381;6104.084387341934;6105.45050945417;5836.766529338949;5895.086261629454;5911.467967882912;5942;5950.2264705587995;5829.213056237354;5861.032929566573;5879.059350503899;5967.194428752063;5974.044152935509;5873.148393483007;5873.794417195378;5880.882382352414;5882.382349853204;5888.587798854039;5847;5892.469677729025;5960.403959604005;5973.372795856546;5975.372585878594;5668.291463567054;5685;5702.743376547983;5772.942270577409;5817.825465236049 10:58:43 INFO - PROCESS | 10518 | |1;ext.html;584.7894757887162;617.2038070888555;637.6046850952408;638.7189636559916;644.0628884972366;33997.66002339976;39530.209395812104;39956.99999999996;39977.80011099941;40276.79861600695;49801.50198498023;50302.245466317945;50317;50343.49656503434;50448.49551504484;8476.957615211915;8864.689735859247;9013.414128081653;9015.504147271897;11070.557177712883;10757.677269681883;11132.666020019413;11137.944310278437;11144.832827507595;11146.888531114666;11301.660950171508;11347.0355019823;11354.659360219206;11358.772824543505;11360.147988900824;11162.888371116265;11269.985701286883;11283.66149015531;11302.265352752045;11314;11063.778724425547;11080.39057851826;11121.22151449399;11160.776784464266;11161.83257251142;45600.54399456029;46060.53939460596;46094.07811843744;46160.3075953861;46216;3778.3954567269107;3840.6543411093135;3916.2559113768293;3943.4479172915817;4118.657747364571;30395.69604303951;30436.695633043608;30482.54276185859;30539.389212215632;30595.38809223803;4588.311753237008;4671.719696818168;4678.7660616969015;4706.905861882743;4730.692504987199;394290;394978;395206;395869;395984.0200799006;63908.36091639117;64247;64273.67863160748;64293.35706642967;64337;39547.80226098851;40024.399634004934;40027.59972400297;41927.371089433684;65995;16227.756583651255;16270;16317.510474685778;16336.75494867578;16342.673146537003;13210.207387556586;13654.522091726742;13663.111897726616;13772.380242889094;13933.372998215105;21155.78844211569;21212.46968825761;21484.355469335944;21611.243606473698;21721.45696357572;7269.418446524265;7299.087614048244;7330.266973302733;7563.508371955805;7576.962115189499;1003.6587560229557;1054.530733823441;1083.2796190533206;1132.1395739238073;1167.544657583543;33446.665533344356;33645.15887102842;33849;34149.82925085359;34248;1143.74837535742;1158.692946369213;1176.1061593189238;1238.9194702344319;1271.9809202861964;17742.37901673461;17920.193591288422;18120;18148.455546333633;18169.091545422674;575528;578139;579346;579378;579472;62778;63366;63452.68273658602;63528.682356587924;63599.36400635934;1349.6221058103747;1436.2316160853893;1473.2191938272733;1492.1718446262428;1493.2533733133432;34193.14517137092;35185;36587.63412365842;36647.81676091602;36783.26433471369;58508.4149158503;62766.68616656888;62821.05768413386;62838.68580657068;62924.37075629368;796480;820554.0750666354;822017;822265;823311;39729.2054158921;40653.390199146445;40980.79509602433;41446.79276603598;43423.34864977091;48141.75929120332;52829.735851320496;52906.470935290155;53234;53296.20055699245;42001.36997944972;44212.55787442085;44465;45142.77428612836;45855;42598.57401425946;43143.78428107965;43511.56488435202;44006.55993440025;44211.77894110638;43071.35392969171;43774;44105.338419923086;44154;44304.778476107414;33654.326913462086;38849.417258741705;39033.2193356137;39279;39674.801625991684;28995.27511812137;29037.915400328326;29116.854415727787;29143.708562914948;29354.412911742074 10:58:43 INFO - PROCESS | 10518 | |2;jquery.html;429.72497601535025;432.28888478452967;435.83104382029137;441.6621284717192;456.64153639393083;18887.811121888775;20142.69785953212;20591.27930522432;20822.063007164677;21097.26159584415;19209.519762005955;19575.902120489376;20445.59108817823;20540;20603.793962060376;5765.308163020443;6002.549808764338;6006.729697163638;6008.969955150218;6010.789622363218;5844.736986835582;5864.208331875185;5870;5883.058710606304;5914;5830.833833233349;5838.503727183194;5848.502877255439;5848.619839710426;5856.180134781134;5804.796832110877;5899.203607512974;5925.585209035369;5931.288245410536;5932.911006334887;5150.304708864331;5210;5353.705546194958;5357.544608708265;5357.812476563302;14110.788338174936;14239.928800356036;20220.49448763778;22375.328740137822;23981.88009059961;2431.270618814366;2470.0737223541173;2484.3664865459373;2673.4920365130565;2869.3543952610703;16255.18724063792;16702.49892503227;16817.41139060127;16983.150842457828;17423.73864392035;2001.6597178479694;2122.2678176029244;2205.2722601541454;2224.399412158708;2225.899834507451;69555;87189.56405217934;92217.53891230568;95482.52258738661;99109.504452478;18237;18515.166817493246;18823.776454530413;19314.71027934582;19455.610887782164;33668.494972575434;34226.14434639104;34499.82750086234;34720;34942.47586286208;9501.952490237505;9915.752106197402;9970.601175952881;10210.846837297446;10235.130013948898;8181.95909020463;8297.626606802709;8317.459365141247;8456.534890580953;8476.067632560413;9114.726558203265;9222.723318300461;9323.021082786345;9366.595010748442;9680.515974201262;3492.163353755017;3620.3845346291196;3664.1755604988666;3723.646253605903;3748.8500459981296;850.2093053460342;873.401719821924;882.7881308485917;908.0329449136648;926.0785518409091;17800.109994500224;18352;18526.347350273376;18649.81350186508;18656.160472778756;938.901415351392;957.9521023948774;961.423146112327;985.4727720669407;987.2990176974429;8528.360372972053;9884.752881178028;10340.37957722539;10375.688729338131;10477.47612619366;522036;524522;530154;532150;546404;94801;96035;96821;97828.51085744526;99137.50431247798;1036.9740756480996;1041.6406339812752;1073.2272763610188;1080.2114456446839;1107.0313475708595;18048.63902721912;18396.632067358845;18596.349127780464;18755.812441875405;19014.334498292494;39937;44285.77857110694;44487.332690009025;44960.32559511544;45669.314960276286;64440;65273.347266526725;65920;66626.33373666201;66806.33193668001;8340.499570025819;8522.551166301868;8579.571021449028;8669.436486628349;8673.045964943725;9477.383970041925;12543.3101179436;12803.935980320039;13089.345532723135;14286.714265714836;11520.308781472802;11948.522059117538;12406.193597416139;12498.937505312779;12826.67933301675;11901.464434100313;11993.280403175837;12076.335801531;12203.69490762738;12373.814392784296;11583.247088939192;11686.298822070703;11765.352905590275;12558.937205313914;12680.619581412573;8613.09562495954;8819.38264321517;8962.283017358726;8975.50634715097;8995.595198216226;12211.938940305241;12569.748605027666;12575.371231438574;12609.810852837398;12801.743965120831 10:58:43 INFO - PROCESS | 10518 | |3;mootools.html;1419.5102689572093;1458.7957685923966;1462.2768457881043;1555.6110972256931;1563.4762354611205;9064.320175986802;9457.574409151588;9536.76158096047;9737.318387712863;9751.12239898409;9566.760830979229;9702.951485242564;9709.174720148778;9709.660161894335;9716.31985760997;2430.2101816909485;2472.61674440462;2474;2474.814388920829;2476.4428003699204;2360.24472168906;2431.781139697427;2439.2438344113293;2445.8287919845616;2447.4615584571457;2372.1578839511985;2376.6316220985773;2395.688560487134;2431.185552839792;2433.197044975163;2349.4009027698;2359.6224604063354;2379.0840526397374;2380.4881950380577;2383.0586918167314;2404.747501512351;2412.49337639096;2450.4976479821594;2453.5338285725657;2454.5090981803532;9406.482643454607;9617.038296170396;9763.511824408752;9764.170045546138;9770.51147442625;5392.757325920343;5618.4662457066515;5626.774929002835;5627.971860140714;5633.690147041911;3530.6116327203986;3550.9822450887837;3604.4413115966804;3615;3622.2936527377174;1243.4777393494753;1244.321844594689;1250.9687257818537;1281.9166754161042;1283.3775618824861;13771.655708607264;13812.240326782023;13835.30823458823;13858.099223550436;13869.930650346785;4395.868123956287;4460.910781784346;4669.95330046702;4677.32178834074;4715.198416269242;10396.116330112025;10399.896001040044;10416;10434.37393756377;10436.791264174673;5484.341878974469;5514.145307477347;5515.420880807537;5518.613697041249;5520.1167813149705;4570.7486088264795;4818.277258411196;4939.53074457926;4959.454460009396;5142;3959.970407693963;3974.5230572331507;4040.676745860324;4073.1853629274265;4086.4891888513935;1163.1276542593055;1173.284296579103;1176.4470698771563;1186.9109816763778;1190.0360707826687;926.5413620258015;958.6405098088217;963.9759006024765;964.0600414595713;1004.8542961270575;6150.354212807681;6209.937900620936;6231.376862313823;6286.842828929313;6294.276158241861;911.4394647292;937.704623043739;938.4088024544493;940.4357385568604;955.4171955107379;3969.861054863066;4127.669786417079;4234.216669916075;4263.5096963849555;4306.913861722748;12177.452014659362;12455.252684838939;12477.688057798629;12484.313362765135;12508.81236781449;12346.382680865921;12358.505659773688;12407.875921240853;12438;12439.813402798965;8541.871871921927;8793.340499462565;8905.109489051043;9069.093090690878;9092.954535227282;6050.364711705208;6056.515478761777;6332.240131184103;6343.333949935375;6395.16862807832;10512.947435262775;10939.56241750321;11048.723781905517;11058.22592418523;11223.66329010131;11124.777504450027;11129.165312601426;11312.773744524899;11454.427278636202;11465.770684586094;7946.086200086833;8523.957380213307;8614.61234244448;8618.78453038679;8839.51382673959;9646.9035309646;9719.173870220828;9725.6596019139;9740.512974351395;9741.610335586498;8071.192880711882;8425.452345597516;8472.322214222724;8474.32205423577;8507.447015943944;7971.72098976533;8010.198980101944;8081.63632636521;8135.511869287861;8146.266835984789;7379.298966598165;7406.925930740839;7431.516951398142;7459.962700186465;7495;5329.54698850602;5367.892642147213;5407.188921661782;5418.187271909245;5460;5234.1102012657175;5333.546648534918;5354.223637572608;5358.919616205838;5362.731863406892 10:58:43 INFO - PROCESS | 10518 | |4;prototype.html;321.41662881869405;330.6131825763856;331.20841189556955;333.1371747174815;335.62410100687225;5414.918776218355;5496.890062198753;5518.558515318775;5520.861978450531;5567.805126820562;5421;5428.267183930178;5433.972830135844;5442.782288708457;5498.532624726903;4102.159057393242;4111.712180147376;4201;4229.154169166164;4244.787760611973;4207.474065741782;4220.910649228621;4238.80925358358;4242.406063151161;4242.83028678854;4209.9789501052455;4216.852410165629;4217.198732240864;4218.367244913257;4226.1547690461875;4067.8169482373214;4240.893977650568;4251.341042138442;4251.704079796906;4255.212785634637;3736.327461057009;3748.475213470116;3758.906027349311;3760.1539653578;3763.473113764075;5349.245138753545;5404.351477822686;5406.243125962368;5408.621396502246;5434.3207099112615;4637.2116740153915;4879.219324907997;4879.951200487985;4891.804327826918;4909.8772530686665;5739.713014349308;5770;5775.826725198251;5777.422257774189;5780.855478613026;4124.277976535503;4128.60778226071;4129.29801933672;4139.8965025874595;4144.274751918402;5847.56143289255;5928.229330187048;5971.731272092767;5975.910361344627;5995.220621319244;4181.1010632713505;4184.14225083857;4185.790710464526;4193.769342686121;4196.202721482909;4234.428352172479;4239.851605193803;4240.703150779477;4242.469691288589;4247.44783178191;4673.976630116827;4839.516048395203;4849.4908034656555;4850.684705494132;4873.756312184377;5741.454561816704;5762.135679648002;5774.797882074107;5787.8553036174435;5789.9710501447225;1441.2721575604462;1454.1493226462637;1456.9271536423137;1457.5263039512201;1462.56854228003;899.8974489820912;938.671464987249;942.9062287746182;952.7713348796377;959.9280053995979;5510.91733623996;5551.111822108443;5580.525655319343;5622.943770562324;5634.267545219095;5801.622894511843;5844.801815627871;5881.882522291971;5897.174395584621;5905.940940590625;5438.15708565173;5503.724813759376;5543.861403464865;5548.639338442988;5599.60802743804;3096.3188098618266;3116.628683379351;3123.0793062267;3126.593542839462;3127.202563346332;5889.5877288590245;6028.517718582503;6042.884990253376;6047.879042419215;6055.273367195965;5893.381194974551;5910.7931222407005;5933.9231269056345;5951.821445356646;5977.551683623746;6020.367861374581;6034.24571928509;6038.215032045806;6051.183090282843;6052.485538729169;5664.725436776692;5812.593118481665;5822.126680997715;5845.210896528914;5869.882602348014;5791.8280644758215;5813.82558523245;5814.970925145347;5818.621789583833;5828.184054232495;5891.764329426775;6023.945809483235;6062.302835173835;6063.242094738158;6072.180255665428;4139.482564679415;4143.150654115839;4144.461220041375;4149.668026557808;4157.314043182841;5873.500752436091;5883.61756485827;5888.970555147197;5914.911276330945;5916.970415147896;5573.693446860461;5680.6875621839545;5701.375108094173;5703;5785.884282314246;5691.345495268097;5691.658500489983;5716.256886604715;5732.570057245806;5747.655140691572;5392.191171324333;5640.4077571856005;5676.744546495335;5712.742926568231;5722.828315150552;5613.691246981455;5771.59598828078;5792.710364481674;5792.94207057924;5807.622504537191;5639.097744360883;5650.7568334966245;5654.519365853784;5655.4910058094965;5667.121596152603 10:58:43 INFO - PROCESS | 10518 | |5;yui.html;794.4081659163924;837.2924878477684;849.1466076593023;862.6333808131543;910.6994691751722;15029.24853757312;15668.608284792886;15712.921435392807;15905.84094159058;15982.360705571778;15593.53219403417;15950.920245398785;16074.115923624197;16213.99999999997;16247;832.126267419209;869.5391442535466;878.9956050219772;880.2254016465502;880.9295256379474;712.1063065852348;844.7761343244046;859.9484030958131;863.8358711844761;869.2871845087031;920.6731610278355;922.3451349541833;923.1691477670117;924.0990034716132;924.7225832250332;909.1953621045357;912.6440688131634;916.8853893263342;918.3800934369308;918.7151982885293;670.6814263224978;671.6104659297596;671.6507416143578;671.6608112903001;671.778313156662;15410.075395476308;15528.844711552852;15532;15549.533513994598;15577;13550.661233469144;13953.651158721012;14437.350319235555;14541.34563944625;16452;14118.788218176736;14186.21975791331;14245.643858903506;14323.856761432357;14357.138571685733;4756.548127927841;4761.119192949281;4772.713637181745;4835.032993401299;4871.805127794884;80993.59503202446;81160;81772;82413.58793205995;82557;11402.714932126763;11421.600243991421;11494.310341379376;11629.709257268636;11693.824592631117;21926.122955081617;21931.451713707283;21964.670529942065;21989.23037693673;22046.779532204473;8689;8833.205011548991;8837.425567338103;8982.550872456351;9035.232005279493;4788.61691064714;5112.105381558287;5149.639525233272;5172.146595811649;5299.6025298102795;3617.4573813927595;3741.56971948224;3750.9437358439645;3754;3761.6426439488205;632.181325183888;635.2948227467507;638.3520726462644;647.4237928243866;651.3161180760183;600.7386786747778;685.5132855672429;688.9724411023603;721.2030706069822;726.5241266970143;9266.305027122993;9737.136048849618;9930.042173158025;10044.754841818198;10356.585736570452;694.9374556289958;702.1433850702135;702.2521015118882;717.7846646006177;759.3469616130124;4614.907701845944;4614.907701846011;4764.714117152982;4795.448523419851;4798.5441383068555;130174;136594;139052;139411;143567.2821635906;113211;114916.42541787238;115372;116610;117634.41182794032;102336;103609;104054.47972760088;104590.47704761427;108678;9205.171534561921;9590.904090959;9630.374025688589;9648.517574121406;9671.854922176033;59168.408315918015;59755;61183.694081529306;61229.77540449255;62566.374336257875;64317;88551.55724221337;88862;90437.09562904287;93121.53439232761;10648.254622176373;10719.088877445505;10736.946315268373;10962.287451315959;10982.835257471304;13102.475900963855;13255.337233138112;13256.270905100311;13275.468981240641;13338.733225335633;12807.871921280668;12972.5459608917;13035.219196222397;13082.869171308164;13131.212127272394;12704.047196460118;12803.29581873006;12858.935705321788;12946.870531294566;13134.934325328311;11309.491072901574;11324.263922844988;11325.546978120783;11386.373749443861;11467.31196128235;10523.842142367717;10935.726606834893;11133.109351251718;11241.55033798639;11241.88758112441;10537;10880.836787448035;11092.389918554558;11125.554977800797;11180 10:58:43 INFO - PROCESS | 10518 | __end_tp_report 10:58:43 INFO - PROCESS | 10518 | __start_cc_report 10:58:43 INFO - PROCESS | 10518 | _x_x_mozilla_cycle_collect,1058 10:58:43 INFO - PROCESS | 10518 | __end_cc_report 10:58:43 INFO - PROCESS | 10518 | __startTimestamp1476467923725__endTimestamp 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | ------- Summary: start ------- 10:58:43 INFO - PROCESS | 10518 | Number of tests: 6 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | [#0] dojo.html Cycles:165 Average:5600.08 Median:5829.21 stddev:641.54 (11.0%) stddev-sans-first:614.98 10:58:43 INFO - PROCESS | 10518 | Values: 3188.3 3191.0 3215.0 3240.2 3246.4 5560.8 5648.2 5657.2 5657.9 5671.3 5623.2 5664.7 5678.1 5680.3 5694.1 5088.6 5119.1 5124.0 5179.8 5192.2 5029.9 5041.9 5187.8 5196.3 5227.1 5064.5 5073.8 5109.2 5109.6 5128.6 5129.6 5157.3 5250.3 5256.4 5284.1 4900.5 5136.0 5151.7 5194.6 5213.9 5404.3 5429.2 5435.3 5452.0 5514.9 6038.4 6068.8 6089.5 6096.4 6097.0 5862.8 5940.4 6133.8 6134.1 6143.8 5567.2 5608.5 5628.0 5664.7 5668.4 6129.6 6132.3 6136.0 6139.4 6143.4 5789.6 5845.7 5904.5 5949.1 5949.2 5959.0 6050.2 6079.8 6082.1 6095.5 6017.8 6020.3 6022.5 6027.5 6053.6 5813.2 5870.3 5980.8 6025.5 6030.0 4593.0 4594.8 4597.1 4659.5 4683.2 4112.0 4141.1 4142.6 4167.2 4175.0 5562.1 5772.7 5781.6 5783.2 5783.7 5977.3 5989.0 6042.7 6095.4 6131.4 5741.8 5748.9 5752.1 5752.8 5758.6 4999.4 5112.9 5114.6 5124.9 5129.0 6133.4 6199.7 6204.4 6253.2 6288.2 6059.9 6079.5 6080.5 6082.4 6122.1 6106.6 6183.9 6299.0 6306.3 6311.2 5869.9 5896.5 5907.3 5970.8 6009.0 6089.9 6097.9 6103.0 6104.1 6105.5 5836.8 5895.1 5911.5 5942.0 5950.2 5829.2 5861.0 5879.1 5967.2 5974.0 5873.1 5873.8 5880.9 5882.4 5888.6 5847.0 5892.5 5960.4 5973.4 5975.4 5668.3 5685.0 5702.7 5772.9 5817.8 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | [#1] ext.html Cycles:180 Average:74915.66 Median:395926.51 stddev:166653.08 (42.1%) stddev-sans-first:167027.14 10:58:43 INFO - PROCESS | 10518 | Values: 584.8 617.2 637.6 638.7 644.1 33997.7 39530.2 39957.0 39977.8 40276.8 49801.5 50302.2 50317.0 50343.5 50448.5 8477.0 8864.7 9013.4 9015.5 11070.6 10757.7 11132.7 11137.9 11144.8 11146.9 11301.7 11347.0 11354.7 11358.8 11360.1 11162.9 11270.0 11283.7 11302.3 11314.0 11063.8 11080.4 11121.2 11160.8 11161.8 45600.5 46060.5 46094.1 46160.3 46216.0 3778.4 3840.7 3916.3 3943.4 4118.7 30395.7 30436.7 30482.5 30539.4 30595.4 4588.3 4671.7 4678.8 4706.9 4730.7 394290.0 394978.0 395206.0 395869.0 395984.0 63908.4 64247.0 64273.7 64293.4 64337.0 39547.8 40024.4 40027.6 41927.4 65995.0 16227.8 16270.0 16317.5 16336.8 16342.7 13210.2 13654.5 13663.1 13772.4 13933.4 21155.8 21212.5 21484.4 21611.2 21721.5 7269.4 7299.1 7330.3 7563.5 7577.0 1003.7 1054.5 1083.3 1132.1 1167.5 33446.7 33645.2 33849.0 34149.8 34248.0 1143.7 1158.7 1176.1 1238.9 1272.0 17742.4 17920.2 18120.0 18148.5 18169.1 575528.0 578139.0 579346.0 579378.0 579472.0 62778.0 63366.0 63452.7 63528.7 63599.4 1349.6 1436.2 1473.2 1492.2 1493.3 34193.1 35185.0 36587.6 36647.8 36783.3 58508.4 62766.7 62821.1 62838.7 62924.4 796480.0 820554.1 822017.0 822265.0 823311.0 39729.2 40653.4 40980.8 41446.8 43423.3 48141.8 52829.7 52906.5 53234.0 53296.2 42001.4 44212.6 44465.0 45142.8 45855.0 42598.6 43143.8 43511.6 44006.6 44211.8 43071.4 43774.0 44105.3 44154.0 44304.8 33654.3 38849.4 39033.2 39279.0 39674.8 28995.3 29037.9 29116.9 29143.7 29354.4 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | [#2] jquery.html Cycles:180 Average:31939.87 Median:431.01 stddev:87510.33 (20303.7%) stddev-sans-first:87723.83 10:58:43 INFO - PROCESS | 10518 | Values: 429.7 432.3 435.8 441.7 456.6 18887.8 20142.7 20591.3 20822.1 21097.3 19209.5 19575.9 20445.6 20540.0 20603.8 5765.3 6002.5 6006.7 6009.0 6010.8 5844.7 5864.2 5870.0 5883.1 5914.0 5830.8 5838.5 5848.5 5848.6 5856.2 5804.8 5899.2 5925.6 5931.3 5932.9 5150.3 5210.0 5353.7 5357.5 5357.8 14110.8 14239.9 20220.5 22375.3 23981.9 2431.3 2470.1 2484.4 2673.5 2869.4 16255.2 16702.5 16817.4 16983.2 17423.7 2001.7 2122.3 2205.3 2224.4 2225.9 69555.0 87189.6 92217.5 95482.5 99109.5 18237.0 18515.2 18823.8 19314.7 19455.6 33668.5 34226.1 34499.8 34720.0 34942.5 9502.0 9915.8 9970.6 10210.8 10235.1 8182.0 8297.6 8317.5 8456.5 8476.1 9114.7 9222.7 9323.0 9366.6 9680.5 3492.2 3620.4 3664.2 3723.6 3748.9 850.2 873.4 882.8 908.0 926.1 17800.1 18352.0 18526.3 18649.8 18656.2 938.9 958.0 961.4 985.5 987.3 8528.4 9884.8 10340.4 10375.7 10477.5 522036.0 524522.0 530154.0 532150.0 546404.0 94801.0 96035.0 96821.0 97828.5 99137.5 1037.0 1041.6 1073.2 1080.2 1107.0 18048.6 18396.6 18596.3 18755.8 19014.3 39937.0 44285.8 44487.3 44960.3 45669.3 64440.0 65273.3 65920.0 66626.3 66806.3 8340.5 8522.6 8579.6 8669.4 8673.0 9477.4 12543.3 12803.9 13089.3 14286.7 11520.3 11948.5 12406.2 12498.9 12826.7 11901.5 11993.3 12076.3 12203.7 12373.8 11583.2 11686.3 11765.4 12558.9 12680.6 8613.1 8819.4 8962.3 8975.5 8995.6 12211.9 12569.7 12575.4 12609.8 12801.7 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | [#3] mootools.html Cycles:180 Average:6240.22 Median:4696.26 stddev:3708.99 (79.0%) stddev-sans-first:3701.70 10:58:43 INFO - PROCESS | 10518 | Values: 1419.5 1458.8 1462.3 1555.6 1563.5 9064.3 9457.6 9536.8 9737.3 9751.1 9566.8 9703.0 9709.2 9709.7 9716.3 2430.2 2472.6 2474.0 2474.8 2476.4 2360.2 2431.8 2439.2 2445.8 2447.5 2372.2 2376.6 2395.7 2431.2 2433.2 2349.4 2359.6 2379.1 2380.5 2383.1 2404.7 2412.5 2450.5 2453.5 2454.5 9406.5 9617.0 9763.5 9764.2 9770.5 5392.8 5618.5 5626.8 5628.0 5633.7 3530.6 3551.0 3604.4 3615.0 3622.3 1243.5 1244.3 1251.0 1281.9 1283.4 13771.7 13812.2 13835.3 13858.1 13869.9 4395.9 4460.9 4670.0 4677.3 4715.2 10396.1 10399.9 10416.0 10434.4 10436.8 5484.3 5514.1 5515.4 5518.6 5520.1 4570.7 4818.3 4939.5 4959.5 5142.0 3960.0 3974.5 4040.7 4073.2 4086.5 1163.1 1173.3 1176.4 1186.9 1190.0 926.5 958.6 964.0 964.1 1004.9 6150.4 6209.9 6231.4 6286.8 6294.3 911.4 937.7 938.4 940.4 955.4 3969.9 4127.7 4234.2 4263.5 4306.9 12177.5 12455.3 12477.7 12484.3 12508.8 12346.4 12358.5 12407.9 12438.0 12439.8 8541.9 8793.3 8905.1 9069.1 9093.0 6050.4 6056.5 6332.2 6343.3 6395.2 10512.9 10939.6 11048.7 11058.2 11223.7 11124.8 11129.2 11312.8 11454.4 11465.8 7946.1 8524.0 8614.6 8618.8 8839.5 9646.9 9719.2 9725.7 9740.5 9741.6 8071.2 8425.5 8472.3 8474.3 8507.4 7971.7 8010.2 8081.6 8135.5 8146.3 7379.3 7406.9 7431.5 7460.0 7495.0 5329.5 5367.9 5407.2 5418.2 5460.0 5234.1 5333.5 5354.2 5358.9 5362.7 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | [#4] prototype.html Cycles:180 Average:4821.39 Median:5559.46 stddev:1425.37 (25.6%) stddev-sans-first:1388.77 10:58:43 INFO - PROCESS | 10518 | Values: 321.4 330.6 331.2 333.1 335.6 5414.9 5496.9 5518.6 5520.9 5567.8 5421.0 5428.3 5434.0 5442.8 5498.5 4102.2 4111.7 4201.0 4229.2 4244.8 4207.5 4220.9 4238.8 4242.4 4242.8 4210.0 4216.9 4217.2 4218.4 4226.2 4067.8 4240.9 4251.3 4251.7 4255.2 3736.3 3748.5 3758.9 3760.2 3763.5 5349.2 5404.4 5406.2 5408.6 5434.3 4637.2 4879.2 4880.0 4891.8 4909.9 5739.7 5770.0 5775.8 5777.4 5780.9 4124.3 4128.6 4129.3 4139.9 4144.3 5847.6 5928.2 5971.7 5975.9 5995.2 4181.1 4184.1 4185.8 4193.8 4196.2 4234.4 4239.9 4240.7 4242.5 4247.4 4674.0 4839.5 4849.5 4850.7 4873.8 5741.5 5762.1 5774.8 5787.9 5790.0 1441.3 1454.1 1456.9 1457.5 1462.6 899.9 938.7 942.9 952.8 959.9 5510.9 5551.1 5580.5 5622.9 5634.3 5801.6 5844.8 5881.9 5897.2 5905.9 5438.2 5503.7 5543.9 5548.6 5599.6 3096.3 3116.6 3123.1 3126.6 3127.2 5889.6 6028.5 6042.9 6047.9 6055.3 5893.4 5910.8 5933.9 5951.8 5977.6 6020.4 6034.2 6038.2 6051.2 6052.5 5664.7 5812.6 5822.1 5845.2 5869.9 5791.8 5813.8 5815.0 5818.6 5828.2 5891.8 6023.9 6062.3 6063.2 6072.2 4139.5 4143.2 4144.5 4149.7 4157.3 5873.5 5883.6 5889.0 5914.9 5917.0 5573.7 5680.7 5701.4 5703.0 5785.9 5691.3 5691.7 5716.3 5732.6 5747.7 5392.2 5640.4 5676.7 5712.7 5722.8 5613.7 5771.6 5792.7 5792.9 5807.6 5639.1 5650.8 5654.5 5655.5 5667.1 10:58:43 INFO - PROCESS | 10518 | 10:58:43 INFO - PROCESS | 10518 | [#5] yui.html Cycles:180 Average:23114.96 Median:3757.82 stddev:35427.99 (942.8%) stddev-sans-first:35487.73 10:58:43 INFO - PROCESS | 10518 | Values: 794.4 837.3 849.1 862.6 910.7 15029.2 15668.6 15712.9 15905.8 15982.4 15593.5 15950.9 16074.1 16214.0 16247.0 832.1 869.5 879.0 880.2 880.9 712.1 844.8 859.9 863.8 869.3 920.7 922.3 923.2 924.1 924.7 909.2 912.6 916.9 918.4 918.7 670.7 671.6 671.7 671.7 671.8 15410.1 15528.8 15532.0 15549.5 15577.0 13550.7 13953.7 14437.4 14541.3 16452.0 14118.8 14186.2 14245.6 14323.9 14357.1 4756.5 4761.1 4772.7 4835.0 4871.8 80993.6 81160.0 81772.0 82413.6 82557.0 11402.7 11421.6 11494.3 11629.7 11693.8 21926.1 21931.5 21964.7 21989.2 22046.8 8689.0 8833.2 8837.4 8982.6 9035.2 4788.6 5112.1 5149.6 5172.1 5299.6 3617.5 3741.6 3750.9 3754.0 3761.6 632.2 635.3 638.4 647.4 651.3 600.7 685.5 689.0 721.2 726.5 9266.3 9737.1 9930.0 10044.8 10356.6 694.9 702.1 702.3 717.8 759.3 4614.9 4614.9 4764.7 4795.4 4798.5 130174.0 136594.0 139052.0 139411.0 143567.3 113211.0 114916.4 115372.0 116610.0 117634.4 102336.0 103609.0 104054.5 104590.5 108678.0 9205.2 9590.9 9630.4 9648.5 9671.9 59168.4 59755.0 61183.7 61229.8 62566.4 64317.0 88551.6 88862.0 90437.1 93121.5 10648.3 10719.1 10736.9 10962.3 10982.8 13102.5 13255.3 13256.3 13275.5 13338.7 12807.9 12972.5 13035.2 13082.9 13131.2 12704.0 12803.3 12858.9 12946.9 13134.9 11309.5 11324.3 11325.5 11386.4 11467.3 10523.8 10935.7 11133.1 11241.6 11241.9 10537.0 10880.8 11092.4 11125.6 11180.0 10:58:43 INFO - PROCESS | 10518 | -------- Summary: end -------- 10:58:43 INFO - PROCESS | 10518 | 10:58:44 INFO - TEST-INFO | 10518: exit 0 10:58:44 INFO - TEST-OK | dromaeo_css | took 1112733ms 10:58:44 INFO - TEST-START | kraken 10:58:44 INFO - operating with platform_type : linux_ 10:58:44 INFO - Initialising browser for kraken test... 10:58:44 INFO - TEST-INFO | started process 10707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Fcuo8/profile http://localhost:58159/getInfo.html) 10:58:48 INFO - PROCESS | 10707 | __metrics Screen width/height:1600/1200 10:58:48 INFO - PROCESS | 10707 | colorDepth:24 10:58:48 INFO - PROCESS | 10707 | Browser inner width/height: 1024/697 10:58:48 INFO - PROCESS | 10707 | __metrics 10:58:49 INFO - PROCESS | 10707 | 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" 10:58:49 INFO - PROCESS | 10707 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:58:49 INFO - PROCESS | 10707 | console.error: PushService: 10:58:49 INFO - PROCESS | 10707 | stateChangeProcessEnqueue: Error transitioning state 10:58:49 INFO - PROCESS | 10707 | UnknownError 10:58:50 INFO - TEST-INFO | 10707: exit 0 10:58:50 INFO - Browser initialized. 10:58:50 INFO - Running cycle 1/1 for kraken test... 10:58:50 INFO - TEST-INFO | started process 10794 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_Fcuo8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:59:01 INFO - PROCESS | 10794 | RSS: Main: 143929344 10:59:01 INFO - PROCESS | 10794 | 10:59:04 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58159/tests/kraken/driver.html?testName=audio-beat-detection) 10:59:05 INFO - PROCESS | 10794 | RSS: Main: 198770688 10:59:05 INFO - PROCESS | 10794 | 10:59:07 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58159/tests/kraken/driver.html?testName=audio-dft) 10:59:08 INFO - PROCESS | 10794 | RSS: Main: 183533568 10:59:08 INFO - PROCESS | 10794 | 10:59:11 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58159/tests/kraken/driver.html?testName=audio-fft) 10:59:11 INFO - PROCESS | 10794 | RSS: Main: 274759680 10:59:11 INFO - PROCESS | 10794 | 10:59:13 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58159/tests/kraken/driver.html?testName=audio-oscillator) 10:59:14 INFO - PROCESS | 10794 | RSS: Main: 180379648 10:59:14 INFO - PROCESS | 10794 | 10:59:16 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58159/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:59:17 INFO - PROCESS | 10794 | RSS: Main: 178655232 10:59:17 INFO - PROCESS | 10794 | 10:59:20 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58159/tests/kraken/driver.html?testName=imaging-darkroom) 10:59:20 INFO - PROCESS | 10794 | RSS: Main: 214900736 10:59:20 INFO - PROCESS | 10794 | 10:59:24 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58159/tests/kraken/driver.html?testName=imaging-desaturate) 10:59:24 INFO - PROCESS | 10794 | RSS: Main: 213889024 10:59:24 INFO - PROCESS | 10794 | 10:59:27 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58159/tests/kraken/driver.html?testName=json-parse-financial) 10:59:28 INFO - PROCESS | 10794 | RSS: Main: 214028288 10:59:28 INFO - PROCESS | 10794 | 10:59:29 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58159/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:59:30 INFO - PROCESS | 10794 | RSS: Main: 176115712 10:59:30 INFO - PROCESS | 10794 | 10:59:31 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:59:32 INFO - PROCESS | 10794 | RSS: Main: 183853056 10:59:32 INFO - PROCESS | 10794 | 10:59:34 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:59:34 INFO - PROCESS | 10794 | RSS: Main: 189628416 10:59:34 INFO - PROCESS | 10794 | 10:59:37 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:59:38 INFO - PROCESS | 10794 | RSS: Main: 190095360 10:59:38 INFO - PROCESS | 10794 | 10:59:40 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:59:41 INFO - PROCESS | 10794 | RSS: Main: 192991232 10:59:41 INFO - PROCESS | 10794 | 10:59:42 INFO - PROCESS | 10794 | Cycle 1(1): loaded http://localhost:58159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58159/tests/kraken/driver.html?testName=ai-astar) 10:59:43 INFO - PROCESS | 10794 | RSS: Main: 242716672 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | __start_tp_report 10:59:43 INFO - PROCESS | 10794 | _x_x_mozilla_page_load 10:59:43 INFO - PROCESS | 10794 | _x_x_mozilla_page_load_details 10:59:43 INFO - PROCESS | 10794 | |i|pagename|runs| 10:59:43 INFO - PROCESS | 10794 | |0;ai-astar;111;111;121;110;110;110;110;112;111;110 10:59:43 INFO - PROCESS | 10794 | |1;audio-beat-detection;154;117;148;175;123;147;148;125;169;117 10:59:43 INFO - PROCESS | 10794 | |2;audio-dft;161;160;161;161;161;161;160;161;162;160 10:59:43 INFO - PROCESS | 10794 | |3;audio-fft;75;75;75;184;77;76;76;76;185;76 10:59:43 INFO - PROCESS | 10794 | |4;audio-oscillator;83;83;152;84;85;84;146;83;84;146 10:59:43 INFO - PROCESS | 10794 | |5;imaging-gaussian-blur;117;117;117;116;117;116;116;116;117;117 10:59:43 INFO - PROCESS | 10794 | |6;imaging-darkroom;146;147;150;146;148;145;146;146;146;145 10:59:43 INFO - PROCESS | 10794 | |7;imaging-desaturate;94;94;95;95;94;94;95;95;94;94 10:59:43 INFO - PROCESS | 10794 | |8;json-parse-financial;50;49;50;50;50;48;49;48;48;48 10:59:43 INFO - PROCESS | 10794 | |9;json-stringify-tinderbox;59;60;59;60;60;59;59;59;61;59 10:59:43 INFO - PROCESS | 10794 | |10;stanford-crypto-aes;72;74;81;73;77;73;73;74;75;72 10:59:43 INFO - PROCESS | 10794 | |11;stanford-crypto-ccm;120;127;119;122;121;120;117;119;155;124 10:59:43 INFO - PROCESS | 10794 | |12;stanford-crypto-pbkdf2;154;156;151;157;150;157;155;156;150;151 10:59:43 INFO - PROCESS | 10794 | |13;stanford-crypto-sha256-iterative;61;62;62;61;65;61;60;60;59;60 10:59:43 INFO - PROCESS | 10794 | __end_tp_report 10:59:43 INFO - PROCESS | 10794 | __start_cc_report 10:59:43 INFO - PROCESS | 10794 | _x_x_mozilla_cycle_collect,1658 10:59:43 INFO - PROCESS | 10794 | __end_cc_report 10:59:43 INFO - PROCESS | 10794 | __startTimestamp1476467983199__endTimestamp 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | ------- Summary: start ------- 10:59:43 INFO - PROCESS | 10794 | Number of tests: 14 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#0] ai-astar Cycles:10 Average:111.60 Median:111.00 stddev:3.37 (3.0%) stddev-sans-first:3.57 10:59:43 INFO - PROCESS | 10794 | Values: 111.0 111.0 121.0 110.0 110.0 110.0 110.0 112.0 111.0 110.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:148.00 stddev:20.97 (14.2%) stddev-sans-first:21.81 10:59:43 INFO - PROCESS | 10794 | Values: 154.0 117.0 148.0 175.0 123.0 147.0 148.0 125.0 169.0 117.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 10:59:43 INFO - PROCESS | 10794 | Values: 161.0 160.0 161.0 161.0 161.0 161.0 160.0 161.0 162.0 160.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#3] audio-fft Cycles:10 Average:97.50 Median:76.00 stddev:45.86 (60.3%) stddev-sans-first:47.91 10:59:43 INFO - PROCESS | 10794 | Values: 75.0 75.0 75.0 184.0 77.0 76.0 76.0 76.0 185.0 76.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#4] audio-oscillator Cycles:10 Average:103.00 Median:83.50 stddev:31.10 (37.2%) stddev-sans-first:32.14 10:59:43 INFO - PROCESS | 10794 | Values: 83.0 83.0 152.0 84.0 85.0 84.0 146.0 83.0 84.0 146.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 10:59:43 INFO - PROCESS | 10794 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 117.0 117.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.00 stddev:1.51 (1.0%) stddev-sans-first:1.59 10:59:43 INFO - PROCESS | 10794 | Values: 146.0 147.0 150.0 146.0 148.0 145.0 146.0 146.0 146.0 145.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:59:43 INFO - PROCESS | 10794 | Values: 94.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#8] json-parse-financial Cycles:10 Average:49.00 Median:49.50 stddev:0.94 (1.9%) stddev-sans-first:0.93 10:59:43 INFO - PROCESS | 10794 | Values: 50.0 49.0 50.0 50.0 50.0 48.0 49.0 48.0 48.0 48.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 10:59:43 INFO - PROCESS | 10794 | Values: 59.0 60.0 59.0 60.0 60.0 59.0 59.0 59.0 61.0 59.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#10] stanford-crypto-aes Cycles:10 Average:74.40 Median:74.00 stddev:2.76 (3.7%) stddev-sans-first:2.78 10:59:43 INFO - PROCESS | 10794 | Values: 72.0 74.0 81.0 73.0 77.0 73.0 73.0 74.0 75.0 72.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:121.50 stddev:11.12 (9.2%) stddev-sans-first:11.68 10:59:43 INFO - PROCESS | 10794 | Values: 120.0 127.0 119.0 122.0 121.0 120.0 117.0 119.0 155.0 124.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:155.50 stddev:2.91 (1.9%) stddev-sans-first:3.08 10:59:43 INFO - PROCESS | 10794 | Values: 154.0 156.0 151.0 157.0 150.0 157.0 155.0 156.0 150.0 151.0 10:59:43 INFO - PROCESS | 10794 | 10:59:43 INFO - PROCESS | 10794 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.66 (2.7%) stddev-sans-first:1.76 10:59:43 INFO - PROCESS | 10794 | Values: 61.0 62.0 62.0 61.0 65.0 61.0 60.0 60.0 59.0 60.0 10:59:43 INFO - PROCESS | 10794 | -------- Summary: end -------- 10:59:43 INFO - PROCESS | 10794 | 10:59:44 INFO - TEST-INFO | 10794: exit 0 10:59:44 INFO - TEST-OK | kraken | took 59953ms 10:59:44 INFO - SUITE-END | took 1172s 10:59:44 INFO - Completed test suite (00:19:32) 10:59:44 INFO - javascript benchmark 10:59:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3188.2826364068083, 3191.042687193842, 3214.9839250803757, 3240.2061494933746, 3246.4318744219754, 5560.833175004746, 5648.152777083435, 5657.151427285909, 5657.9434205657935, 5671.291088613918, 5623.212750214963, 5664.745086471108, 5678.091505359143, 5680.318361796591, 5694.145878118285, 5088.643794934347, 5119.129747942859, 5124.0, 5179.8446046618665, 5192.221166824968, 5029.924551131718, 5041.924371134436, 5187.766550505217, 5196.324477817879, 5227.059129356714, 5064.493550644925, 5073.822416215433, 5109.20807274873, 5109.642325037249, 5128.615353848476, 5129.615278854082, 5157.252198431244, 5250.317458730341, 5256.448072952361, 5284.101702710548, 4900.460949295611, 5135.998480296306, 5151.716655583941, 5194.584433245332, 5213.869653258661, 5404.270423492818, 5429.185622156708, 5435.347758268994, 5451.972740136313, 5514.91727624086, 6038.396160383971, 6068.848278793065, 6089.543284253698, 6096.420840020191, 6097.0, 5862.824115276549, 5940.405959404068, 6133.754649814002, 6134.141220229171, 6143.815685529396, 5567.248421463091, 5608.49523542879, 5628.0, 5664.745086471077, 5668.376478587352, 6129.570930034944, 6132.325444201134, 6136.048912418582, 6139.386061393914, 6143.354947730424, 5789.623674461231, 5845.707714614252, 5904.527637788967, 5949.137375080676, 5949.167116603679, 5959.0, 6050.184944516715, 6079.787207447718, 6082.057281121433, 6095.4818840398175, 6017.789377371771, 6020.307664618537, 6022.518198544106, 6027.487663548647, 6053.636781793107, 5813.215215945793, 5870.26621672291, 5980.760769569256, 6025.457708806229, 6030.0, 4593.035462552834, 4594.816207351735, 4597.126545956257, 4659.487456379796, 4683.227267500893, 4111.958880411217, 4141.109661422808, 4142.5857414258935, 4167.187398457332, 4175.039740859619, 5562.137868630369, 5772.6536407815665, 5781.624194427349, 5783.16144159103, 5783.652980821164, 5977.282726072812, 5988.970055149696, 6042.728077236535, 6095.359987201368, 6131.417515336036, 5741.827745167651, 5748.885022299531, 5752.07966725322, 5752.827415177551, 5758.596898217168, 4999.42506611737, 5112.872178195575, 5114.616403769732, 5124.948750512522, 5129.025485157883, 6133.365267548864, 6199.721012554446, 6204.3795620437595, 6253.249610046823, 6288.151099601587, 6059.909101363395, 6079.513638908788, 6080.5095287853555, 6082.422169893853, 6122.081687746695, 6106.6336019838955, 6183.907241391473, 6299.023651334051, 6306.306306306211, 6311.179546659088, 5869.853253668522, 5896.498797602252, 5907.320658124198, 5970.791022314198, 6009.038553831366, 6089.908651370321, 6097.939020609914, 6103.023516237381, 6104.084387341934, 6105.45050945417, 5836.766529338949, 5895.086261629454, 5911.467967882912, 5942.0, 5950.2264705587995, 5829.213056237354, 5861.032929566573, 5879.059350503899, 5967.194428752063, 5974.044152935509, 5873.148393483007, 5873.794417195378, 5880.882382352414, 5882.382349853204, 5888.587798854039, 5847.0, 5892.469677729025, 5960.403959604005, 5973.372795856546, 5975.372585878594, 5668.291463567054, 5685.0, 5702.743376547983, 5772.942270577409, 5817.825465236049], "value": 5556.189773198003, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.7894757887162, 617.2038070888555, 637.6046850952408, 638.7189636559916, 644.0628884972366, 33997.66002339976, 39530.209395812104, 39956.99999999996, 39977.80011099941, 40276.79861600695, 49801.50198498023, 50302.245466317945, 50317.0, 50343.49656503434, 50448.49551504484, 8476.957615211915, 8864.689735859247, 9013.414128081653, 9015.504147271897, 11070.557177712883, 10757.677269681883, 11132.666020019413, 11137.944310278437, 11144.832827507595, 11146.888531114666, 11301.660950171508, 11347.0355019823, 11354.659360219206, 11358.772824543505, 11360.147988900824, 11162.888371116265, 11269.985701286883, 11283.66149015531, 11302.265352752045, 11314.0, 11063.778724425547, 11080.39057851826, 11121.22151449399, 11160.776784464266, 11161.83257251142, 45600.54399456029, 46060.53939460596, 46094.07811843744, 46160.3075953861, 46216.0, 3778.3954567269107, 3840.6543411093135, 3916.2559113768293, 3943.4479172915817, 4118.657747364571, 30395.69604303951, 30436.695633043608, 30482.54276185859, 30539.389212215632, 30595.38809223803, 4588.311753237008, 4671.719696818168, 4678.7660616969015, 4706.905861882743, 4730.692504987199, 394290.0, 394978.0, 395206.0, 395869.0, 395984.0200799006, 63908.36091639117, 64247.0, 64273.67863160748, 64293.35706642967, 64337.0, 39547.80226098851, 40024.399634004934, 40027.59972400297, 41927.371089433684, 65995.0, 16227.756583651255, 16270.0, 16317.510474685778, 16336.75494867578, 16342.673146537003, 13210.207387556586, 13654.522091726742, 13663.111897726616, 13772.380242889094, 13933.372998215105, 21155.78844211569, 21212.46968825761, 21484.355469335944, 21611.243606473698, 21721.45696357572, 7269.418446524265, 7299.087614048244, 7330.266973302733, 7563.508371955805, 7576.962115189499, 1003.6587560229557, 1054.530733823441, 1083.2796190533206, 1132.1395739238073, 1167.544657583543, 33446.665533344356, 33645.15887102842, 33849.0, 34149.82925085359, 34248.0, 1143.74837535742, 1158.692946369213, 1176.1061593189238, 1238.9194702344319, 1271.9809202861964, 17742.37901673461, 17920.193591288422, 18120.0, 18148.455546333633, 18169.091545422674, 575528.0, 578139.0, 579346.0, 579378.0, 579472.0, 62778.0, 63366.0, 63452.68273658602, 63528.682356587924, 63599.36400635934, 1349.6221058103747, 1436.2316160853893, 1473.2191938272733, 1492.1718446262428, 1493.2533733133432, 34193.14517137092, 35185.0, 36587.63412365842, 36647.81676091602, 36783.26433471369, 58508.4149158503, 62766.68616656888, 62821.05768413386, 62838.68580657068, 62924.37075629368, 796480.0, 820554.0750666354, 822017.0, 822265.0, 823311.0, 39729.2054158921, 40653.390199146445, 40980.79509602433, 41446.79276603598, 43423.34864977091, 48141.75929120332, 52829.735851320496, 52906.470935290155, 53234.0, 53296.20055699245, 42001.36997944972, 44212.55787442085, 44465.0, 45142.77428612836, 45855.0, 42598.57401425946, 43143.78428107965, 43511.56488435202, 44006.55993440025, 44211.77894110638, 43071.35392969171, 43774.0, 44105.338419923086, 44154.0, 44304.778476107414, 33654.326913462086, 38849.417258741705, 39033.2193356137, 39279.0, 39674.801625991684, 28995.27511812137, 29037.915400328326, 29116.854415727787, 29143.708562914948, 29354.412911742074], "value": 22189.773471275515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.72497601535025, 432.28888478452967, 435.83104382029137, 441.6621284717192, 456.64153639393083, 18887.811121888775, 20142.69785953212, 20591.27930522432, 20822.063007164677, 21097.26159584415, 19209.519762005955, 19575.902120489376, 20445.59108817823, 20540.0, 20603.793962060376, 5765.308163020443, 6002.549808764338, 6006.729697163638, 6008.969955150218, 6010.789622363218, 5844.736986835582, 5864.208331875185, 5870.0, 5883.058710606304, 5914.0, 5830.833833233349, 5838.503727183194, 5848.502877255439, 5848.619839710426, 5856.180134781134, 5804.796832110877, 5899.203607512974, 5925.585209035369, 5931.288245410536, 5932.911006334887, 5150.304708864331, 5210.0, 5353.705546194958, 5357.544608708265, 5357.812476563302, 14110.788338174936, 14239.928800356036, 20220.49448763778, 22375.328740137822, 23981.88009059961, 2431.270618814366, 2470.0737223541173, 2484.3664865459373, 2673.4920365130565, 2869.3543952610703, 16255.18724063792, 16702.49892503227, 16817.41139060127, 16983.150842457828, 17423.73864392035, 2001.6597178479694, 2122.2678176029244, 2205.2722601541454, 2224.399412158708, 2225.899834507451, 69555.0, 87189.56405217934, 92217.53891230568, 95482.52258738661, 99109.504452478, 18237.0, 18515.166817493246, 18823.776454530413, 19314.71027934582, 19455.610887782164, 33668.494972575434, 34226.14434639104, 34499.82750086234, 34720.0, 34942.47586286208, 9501.952490237505, 9915.752106197402, 9970.601175952881, 10210.846837297446, 10235.130013948898, 8181.95909020463, 8297.626606802709, 8317.459365141247, 8456.534890580953, 8476.067632560413, 9114.726558203265, 9222.723318300461, 9323.021082786345, 9366.595010748442, 9680.515974201262, 3492.163353755017, 3620.3845346291196, 3664.1755604988666, 3723.646253605903, 3748.8500459981296, 850.2093053460342, 873.401719821924, 882.7881308485917, 908.0329449136648, 926.0785518409091, 17800.109994500224, 18352.0, 18526.347350273376, 18649.81350186508, 18656.160472778756, 938.901415351392, 957.9521023948774, 961.423146112327, 985.4727720669407, 987.2990176974429, 8528.360372972053, 9884.752881178028, 10340.37957722539, 10375.688729338131, 10477.47612619366, 522036.0, 524522.0, 530154.0, 532150.0, 546404.0, 94801.0, 96035.0, 96821.0, 97828.51085744526, 99137.50431247798, 1036.9740756480996, 1041.6406339812752, 1073.2272763610188, 1080.2114456446839, 1107.0313475708595, 18048.63902721912, 18396.632067358845, 18596.349127780464, 18755.812441875405, 19014.334498292494, 39937.0, 44285.77857110694, 44487.332690009025, 44960.32559511544, 45669.314960276286, 64440.0, 65273.347266526725, 65920.0, 66626.33373666201, 66806.33193668001, 8340.499570025819, 8522.551166301868, 8579.571021449028, 8669.436486628349, 8673.045964943725, 9477.383970041925, 12543.3101179436, 12803.935980320039, 13089.345532723135, 14286.714265714836, 11520.308781472802, 11948.522059117538, 12406.193597416139, 12498.937505312779, 12826.67933301675, 11901.464434100313, 11993.280403175837, 12076.335801531, 12203.69490762738, 12373.814392784296, 11583.247088939192, 11686.298822070703, 11765.352905590275, 12558.937205313914, 12680.619581412573, 8613.09562495954, 8819.38264321517, 8962.283017358726, 8975.50634715097, 8995.595198216226, 12211.938940305241, 12569.748605027666, 12575.371231438574, 12609.810852837398, 12801.743965120831], "value": 10334.430120800585, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.5102689572093, 1458.7957685923966, 1462.2768457881043, 1555.6110972256931, 1563.4762354611205, 9064.320175986802, 9457.574409151588, 9536.76158096047, 9737.318387712863, 9751.12239898409, 9566.760830979229, 9702.951485242564, 9709.174720148778, 9709.660161894335, 9716.31985760997, 2430.2101816909485, 2472.61674440462, 2474.0, 2474.814388920829, 2476.4428003699204, 2360.24472168906, 2431.781139697427, 2439.2438344113293, 2445.8287919845616, 2447.4615584571457, 2372.1578839511985, 2376.6316220985773, 2395.688560487134, 2431.185552839792, 2433.197044975163, 2349.4009027698, 2359.6224604063354, 2379.0840526397374, 2380.4881950380577, 2383.0586918167314, 2404.747501512351, 2412.49337639096, 2450.4976479821594, 2453.5338285725657, 2454.5090981803532, 9406.482643454607, 9617.038296170396, 9763.511824408752, 9764.170045546138, 9770.51147442625, 5392.757325920343, 5618.4662457066515, 5626.774929002835, 5627.971860140714, 5633.690147041911, 3530.6116327203986, 3550.9822450887837, 3604.4413115966804, 3615.0, 3622.2936527377174, 1243.4777393494753, 1244.321844594689, 1250.9687257818537, 1281.9166754161042, 1283.3775618824861, 13771.655708607264, 13812.240326782023, 13835.30823458823, 13858.099223550436, 13869.930650346785, 4395.868123956287, 4460.910781784346, 4669.95330046702, 4677.32178834074, 4715.198416269242, 10396.116330112025, 10399.896001040044, 10416.0, 10434.37393756377, 10436.791264174673, 5484.341878974469, 5514.145307477347, 5515.420880807537, 5518.613697041249, 5520.1167813149705, 4570.7486088264795, 4818.277258411196, 4939.53074457926, 4959.454460009396, 5142.0, 3959.970407693963, 3974.5230572331507, 4040.676745860324, 4073.1853629274265, 4086.4891888513935, 1163.1276542593055, 1173.284296579103, 1176.4470698771563, 1186.9109816763778, 1190.0360707826687, 926.5413620258015, 958.6405098088217, 963.9759006024765, 964.0600414595713, 1004.8542961270575, 6150.354212807681, 6209.937900620936, 6231.376862313823, 6286.842828929313, 6294.276158241861, 911.4394647292, 937.704623043739, 938.4088024544493, 940.4357385568604, 955.4171955107379, 3969.861054863066, 4127.669786417079, 4234.216669916075, 4263.5096963849555, 4306.913861722748, 12177.452014659362, 12455.252684838939, 12477.688057798629, 12484.313362765135, 12508.81236781449, 12346.382680865921, 12358.505659773688, 12407.875921240853, 12438.0, 12439.813402798965, 8541.871871921927, 8793.340499462565, 8905.109489051043, 9069.093090690878, 9092.954535227282, 6050.364711705208, 6056.515478761777, 6332.240131184103, 6343.333949935375, 6395.16862807832, 10512.947435262775, 10939.56241750321, 11048.723781905517, 11058.22592418523, 11223.66329010131, 11124.777504450027, 11129.165312601426, 11312.773744524899, 11454.427278636202, 11465.770684586094, 7946.086200086833, 8523.957380213307, 8614.61234244448, 8618.78453038679, 8839.51382673959, 9646.9035309646, 9719.173870220828, 9725.6596019139, 9740.512974351395, 9741.610335586498, 8071.192880711882, 8425.452345597516, 8472.322214222724, 8474.32205423577, 8507.447015943944, 7971.72098976533, 8010.198980101944, 8081.63632636521, 8135.511869287861, 8146.266835984789, 7379.298966598165, 7406.925930740839, 7431.516951398142, 7459.962700186465, 7495.0, 5329.54698850602, 5367.892642147213, 5407.188921661782, 5418.187271909245, 5460.0, 5234.1102012657175, 5333.546648534918, 5354.223637572608, 5358.919616205838, 5362.731863406892], "value": 4898.840508466934, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.41662881869405, 330.6131825763856, 331.20841189556955, 333.1371747174815, 335.62410100687225, 5414.918776218355, 5496.890062198753, 5518.558515318775, 5520.861978450531, 5567.805126820562, 5421.0, 5428.267183930178, 5433.972830135844, 5442.782288708457, 5498.532624726903, 4102.159057393242, 4111.712180147376, 4201.0, 4229.154169166164, 4244.787760611973, 4207.474065741782, 4220.910649228621, 4238.80925358358, 4242.406063151161, 4242.83028678854, 4209.9789501052455, 4216.852410165629, 4217.198732240864, 4218.367244913257, 4226.1547690461875, 4067.8169482373214, 4240.893977650568, 4251.341042138442, 4251.704079796906, 4255.212785634637, 3736.327461057009, 3748.475213470116, 3758.906027349311, 3760.1539653578, 3763.473113764075, 5349.245138753545, 5404.351477822686, 5406.243125962368, 5408.621396502246, 5434.3207099112615, 4637.2116740153915, 4879.219324907997, 4879.951200487985, 4891.804327826918, 4909.8772530686665, 5739.713014349308, 5770.0, 5775.826725198251, 5777.422257774189, 5780.855478613026, 4124.277976535503, 4128.60778226071, 4129.29801933672, 4139.8965025874595, 4144.274751918402, 5847.56143289255, 5928.229330187048, 5971.731272092767, 5975.910361344627, 5995.220621319244, 4181.1010632713505, 4184.14225083857, 4185.790710464526, 4193.769342686121, 4196.202721482909, 4234.428352172479, 4239.851605193803, 4240.703150779477, 4242.469691288589, 4247.44783178191, 4673.976630116827, 4839.516048395203, 4849.4908034656555, 4850.684705494132, 4873.756312184377, 5741.454561816704, 5762.135679648002, 5774.797882074107, 5787.8553036174435, 5789.9710501447225, 1441.2721575604462, 1454.1493226462637, 1456.9271536423137, 1457.5263039512201, 1462.56854228003, 899.8974489820912, 938.671464987249, 942.9062287746182, 952.7713348796377, 959.9280053995979, 5510.91733623996, 5551.111822108443, 5580.525655319343, 5622.943770562324, 5634.267545219095, 5801.622894511843, 5844.801815627871, 5881.882522291971, 5897.174395584621, 5905.940940590625, 5438.15708565173, 5503.724813759376, 5543.861403464865, 5548.639338442988, 5599.60802743804, 3096.3188098618266, 3116.628683379351, 3123.0793062267, 3126.593542839462, 3127.202563346332, 5889.5877288590245, 6028.517718582503, 6042.884990253376, 6047.879042419215, 6055.273367195965, 5893.381194974551, 5910.7931222407005, 5933.9231269056345, 5951.821445356646, 5977.551683623746, 6020.367861374581, 6034.24571928509, 6038.215032045806, 6051.183090282843, 6052.485538729169, 5664.725436776692, 5812.593118481665, 5822.126680997715, 5845.210896528914, 5869.882602348014, 5791.8280644758215, 5813.82558523245, 5814.970925145347, 5818.621789583833, 5828.184054232495, 5891.764329426775, 6023.945809483235, 6062.302835173835, 6063.242094738158, 6072.180255665428, 4139.482564679415, 4143.150654115839, 4144.461220041375, 4149.668026557808, 4157.314043182841, 5873.500752436091, 5883.61756485827, 5888.970555147197, 5914.911276330945, 5916.970415147896, 5573.693446860461, 5680.6875621839545, 5701.375108094173, 5703.0, 5785.884282314246, 5691.345495268097, 5691.658500489983, 5716.256886604715, 5732.570057245806, 5747.655140691572, 5392.191171324333, 5640.4077571856005, 5676.744546495335, 5712.742926568231, 5722.828315150552, 5613.691246981455, 5771.59598828078, 5792.710364481674, 5792.94207057924, 5807.622504537191, 5639.097744360883, 5650.7568334966245, 5654.519365853784, 5655.4910058094965, 5667.121596152603], "value": 4360.096154047188, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.4081659163924, 837.2924878477684, 849.1466076593023, 862.6333808131543, 910.6994691751722, 15029.24853757312, 15668.608284792886, 15712.921435392807, 15905.84094159058, 15982.360705571778, 15593.53219403417, 15950.920245398785, 16074.115923624197, 16213.99999999997, 16247.0, 832.126267419209, 869.5391442535466, 878.9956050219772, 880.2254016465502, 880.9295256379474, 712.1063065852348, 844.7761343244046, 859.9484030958131, 863.8358711844761, 869.2871845087031, 920.6731610278355, 922.3451349541833, 923.1691477670117, 924.0990034716132, 924.7225832250332, 909.1953621045357, 912.6440688131634, 916.8853893263342, 918.3800934369308, 918.7151982885293, 670.6814263224978, 671.6104659297596, 671.6507416143578, 671.6608112903001, 671.778313156662, 15410.075395476308, 15528.844711552852, 15532.0, 15549.533513994598, 15577.0, 13550.661233469144, 13953.651158721012, 14437.350319235555, 14541.34563944625, 16452.0, 14118.788218176736, 14186.21975791331, 14245.643858903506, 14323.856761432357, 14357.138571685733, 4756.548127927841, 4761.119192949281, 4772.713637181745, 4835.032993401299, 4871.805127794884, 80993.59503202446, 81160.0, 81772.0, 82413.58793205995, 82557.0, 11402.714932126763, 11421.600243991421, 11494.310341379376, 11629.709257268636, 11693.824592631117, 21926.122955081617, 21931.451713707283, 21964.670529942065, 21989.23037693673, 22046.779532204473, 8689.0, 8833.205011548991, 8837.425567338103, 8982.550872456351, 9035.232005279493, 4788.61691064714, 5112.105381558287, 5149.639525233272, 5172.146595811649, 5299.6025298102795, 3617.4573813927595, 3741.56971948224, 3750.9437358439645, 3754.0, 3761.6426439488205, 632.181325183888, 635.2948227467507, 638.3520726462644, 647.4237928243866, 651.3161180760183, 600.7386786747778, 685.5132855672429, 688.9724411023603, 721.2030706069822, 726.5241266970143, 9266.305027122993, 9737.136048849618, 9930.042173158025, 10044.754841818198, 10356.585736570452, 694.9374556289958, 702.1433850702135, 702.2521015118882, 717.7846646006177, 759.3469616130124, 4614.907701845944, 4614.907701846011, 4764.714117152982, 4795.448523419851, 4798.5441383068555, 130174.0, 136594.0, 139052.0, 139411.0, 143567.2821635906, 113211.0, 114916.42541787238, 115372.0, 116610.0, 117634.41182794032, 102336.0, 103609.0, 104054.47972760088, 104590.47704761427, 108678.0, 9205.171534561921, 9590.904090959, 9630.374025688589, 9648.517574121406, 9671.854922176033, 59168.408315918015, 59755.0, 61183.694081529306, 61229.77540449255, 62566.374336257875, 64317.0, 88551.55724221337, 88862.0, 90437.09562904287, 93121.53439232761, 10648.254622176373, 10719.088877445505, 10736.946315268373, 10962.287451315959, 10982.835257471304, 13102.475900963855, 13255.337233138112, 13256.270905100311, 13275.468981240641, 13338.733225335633, 12807.871921280668, 12972.5459608917, 13035.219196222397, 13082.869171308164, 13131.212127272394, 12704.047196460118, 12803.29581873006, 12858.935705321788, 12946.870531294566, 13134.934325328311, 11309.491072901574, 11324.263922844988, 11325.546978120783, 11386.373749443861, 11467.31196128235, 10523.842142367717, 10935.726606834893, 11133.109351251718, 11241.55033798639, 11241.88758112441, 10537.0, 10880.836787448035, 11092.389918554558, 11125.554977800797, 11180.0], "value": 7880.2585375480085, "unit": "score"}], "name": "dromaeo_css", "value": 7736.872460905476, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 121.0, 110.0, 110.0, 110.0, 110.0, 112.0, 111.0, 110.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 148.0, 175.0, 123.0, 147.0, 148.0, 125.0, 169.0, 117.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 161.0, 162.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 184.0, 77.0, 76.0, 76.0, 76.0, 185.0, 76.0], "value": 97.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 152.0, 84.0, 85.0, 84.0, 146.0, 83.0, 84.0, 146.0], "value": 103.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 150.0, 146.0, 148.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 50.0, 50.0, 48.0, 49.0, 48.0, 48.0, 48.0], "value": 49.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 61.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 81.0, 73.0, 77.0, 73.0, 73.0, 74.0, 75.0, 72.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 127.0, 119.0, 122.0, 121.0, 120.0, 117.0, 119.0, 155.0, 124.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 156.0, 151.0, 157.0, 150.0, 157.0, 155.0, 156.0, 150.0, 151.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 61.0, 65.0, 61.0, 60.0, 60.0, 59.0, 60.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1494.8000000000002, "alertThreshold": 2.0}]} 10:59:44 INFO - Return code: 0 10:59:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:59:44 INFO - # TBPL SUCCESS # 10:59:44 INFO - Running post-action listener: _resource_record_post_action 10:59:44 INFO - [mozharness: 2016-10-14 17:59:44.521633Z] Finished run-tests step (success) 10:59:44 INFO - Running post-run listener: _resource_record_post_run 10:59:44 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290676736; Read time: 16; Write time: 129516 10:59:44 INFO - TinderboxPrint: CPU usage
12.9% 10:59:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 10:59:44 INFO - TinderboxPrint: I/O write bytes / time
290,676,736 / 129,516 10:59:44 INFO - TinderboxPrint: CPU idle
8,252.3 (87.1%) 10:59:44 INFO - TinderboxPrint: CPU user
1,178.7 (12.4%) 10:59:44 INFO - TinderboxPrint: Swap in / out
0 / 0 10:59:44 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 67588096; Read time: 0; Write time: 32672 10:59:44 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 218529792; Read time: 0; Write time: 96380 10:59:45 INFO - Running post-run listener: _upload_blobber_files 10:59:45 INFO - Blob upload gear active. 10:59:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:59:45 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 10:59:45 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:59:45 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:59:45 INFO - (blobuploader) - INFO - Open directory for files ... 10:59:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:59:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:59:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:48 INFO - (blobuploader) - INFO - Done attempting. 10:59:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:59:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:59:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:49 INFO - (blobuploader) - INFO - Done attempting. 10:59:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:59:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:59:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:49 INFO - (blobuploader) - INFO - Done attempting. 10:59:49 INFO - (blobuploader) - INFO - Iteration through files over. 10:59:49 INFO - Return code: 0 10:59:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:59:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:59:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37d5db2d20e0bdd1a6f49fa42ebe98b157f69349cf46316b85a7ec3105984017754a5029ea77373bfa1860c962d9eeb3ab1536ca98eb05103c17585ab12d0883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e727b9369702a303b886a480144ecaf5e2d112e164d5c2284e57b4cd6e00c88170adf8de144ef2b2e6c101c1be915d14716496eac2481765779dc3b3d40ff979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eef07a89b06930970770828e6d0cd74dac3982f6e7043dbf693a5cae8ca560f8d277bce7498cc07c244443d0870908f0098f858fa50bce3a97fb833dbb3a74f"} 10:59:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:59:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:59:49 INFO - Contents: 10:59:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37d5db2d20e0bdd1a6f49fa42ebe98b157f69349cf46316b85a7ec3105984017754a5029ea77373bfa1860c962d9eeb3ab1536ca98eb05103c17585ab12d0883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e727b9369702a303b886a480144ecaf5e2d112e164d5c2284e57b4cd6e00c88170adf8de144ef2b2e6c101c1be915d14716496eac2481765779dc3b3d40ff979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eef07a89b06930970770828e6d0cd74dac3982f6e7043dbf693a5cae8ca560f8d277bce7498cc07c244443d0870908f0098f858fa50bce3a97fb833dbb3a74f"} 10:59:49 INFO - Running post-run listener: copy_logs_to_upload_dir 10:59:49 INFO - Copying logs to upload dir... 10:59:49 INFO - mkdir: /builds/slave/test/build/upload/logs 10:59:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.064710 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-14 10:59:49.802279) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 10:59:49.806179) ========= 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=db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521 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-Beta/sha512/37d5db2d20e0bdd1a6f49fa42ebe98b157f69349cf46316b85a7ec3105984017754a5029ea77373bfa1860c962d9eeb3ab1536ca98eb05103c17585ab12d0883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e727b9369702a303b886a480144ecaf5e2d112e164d5c2284e57b4cd6e00c88170adf8de144ef2b2e6c101c1be915d14716496eac2481765779dc3b3d40ff979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eef07a89b06930970770828e6d0cd74dac3982f6e7043dbf693a5cae8ca560f8d277bce7498cc07c244443d0870908f0098f858fa50bce3a97fb833dbb3a74f"} build_url:https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011437 build_url: 'https://queue.taskcluster.net/v1/task/G4cSmpsWT4Ofd0HELKBbuQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37d5db2d20e0bdd1a6f49fa42ebe98b157f69349cf46316b85a7ec3105984017754a5029ea77373bfa1860c962d9eeb3ab1536ca98eb05103c17585ab12d0883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e727b9369702a303b886a480144ecaf5e2d112e164d5c2284e57b4cd6e00c88170adf8de144ef2b2e6c101c1be915d14716496eac2481765779dc3b3d40ff979", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eef07a89b06930970770828e6d0cd74dac3982f6e7043dbf693a5cae8ca560f8d277bce7498cc07c244443d0870908f0098f858fa50bce3a97fb833dbb3a74f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 10:59:49.831396) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:59:49.831737) ========= 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=db0483c52d987ecb441a263b000001ad-1476466312.344764-674835521 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 10:59:49.915575) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 10:59:49.915906) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 10:59:49.916193) ========= ========= Total master_lag: 0.17 =========