builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1475555823.07 results: success (0) buildid: 20161003194901 builduid: 33b42db2aa034560befbe69970fd1c7e revision: ca2593daaa91f717e3ae65f049308309a31969a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.066076) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.066610) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.066917) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.081450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.081756) ========= 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=20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-03 21:37:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-10-03 21:37:03 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089203 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.184488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.184791) ========= 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=20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015841 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.233565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:03.233878) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ca2593daaa91f717e3ae65f049308309a31969a2 --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 ca2593daaa91f717e3ae65f049308309a31969a2 --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=20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-03 21:37:03,510 truncating revision to first 12 chars 2016-10-03 21:37:03,510 Setting DEBUG logging. 2016-10-03 21:37:03,511 attempt 1/10 2016-10-03 21:37:03,511 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ca2593daaa91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 21:37:03,815 unpacking tar archive at: mozilla-beta-ca2593daaa91/testing/mozharness/ program finished with exit code 0 elapsedTime=0.923103 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:04.169897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:04.170356) ========= script_repo_revision: ca2593daaa91f717e3ae65f049308309a31969a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:04.170771) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:04.171041) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 21:37:04.178877) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-03 21:37:04.179157) ========= /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=20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:37:04 INFO - MultiFileLogger online at 20161003 21:37:04 in /builds/slave/test 21:37:04 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 21:37:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:37:04 INFO - {'append_to_log': False, 21:37:04 INFO - 'base_work_dir': '/builds/slave/test', 21:37:04 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:37:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:37:04 INFO - 'branch': 'Mozilla-Beta', 21:37:04 INFO - 'buildbot_json_path': 'buildprops.json', 21:37:04 INFO - 'config_files': ('talos/linux_config.py',), 21:37:04 INFO - 'default_actions': ('clobber', 21:37:04 INFO - 'read-buildbot-config', 21:37:04 INFO - 'download-and-extract', 21:37:04 INFO - 'populate-webroot', 21:37:04 INFO - 'create-virtualenv', 21:37:04 INFO - 'install', 21:37:04 INFO - 'run-tests'), 21:37:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:37:04 INFO - 'download_minidump_stackwalk': True, 21:37:04 INFO - 'download_symbols': 'ondemand', 21:37:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:37:04 INFO - 'tooltool.py': '/tools/tooltool.py', 21:37:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:37:04 INFO - '/tools/misc-python/virtualenv.py')}, 21:37:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:37:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:37:04 INFO - 'installer_path': 'installer.exe', 21:37:04 INFO - 'log_level': 'info', 21:37:04 INFO - 'log_name': 'talos', 21:37:04 INFO - 'log_to_console': True, 21:37:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:37:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:37:04 INFO - 'opt_config_files': (), 21:37:04 INFO - 'pip_index': False, 21:37:04 INFO - 'sps_profile': False, 21:37:04 INFO - 'sps_profile_interval': 0, 21:37:04 INFO - 'suite': 'dromaeojs', 21:37:04 INFO - 'system_bits': '32', 21:37:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:37:04 INFO - 'title': 'talos-linux64-ix-015', 21:37:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:37:04 INFO - 'use_talos_json': True, 21:37:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:37:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:37:04 INFO - 'work_dir': 'build'} 21:37:04 INFO - [mozharness: 2016-10-04 04:37:04.535367Z] Running clobber step. 21:37:04 INFO - Running pre-action listener: _resource_record_pre_action 21:37:04 INFO - Running main action method: clobber 21:37:04 INFO - rmtree: /builds/slave/test/build 21:37:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:37:05 INFO - Running post-action listener: _resource_record_post_action 21:37:05 INFO - [mozharness: 2016-10-04 04:37:05.505687Z] Finished clobber step (success) 21:37:05 INFO - [mozharness: 2016-10-04 04:37:05.505788Z] Running read-buildbot-config step. 21:37:05 INFO - Running pre-action listener: _resource_record_pre_action 21:37:05 INFO - Running main action method: read_buildbot_config 21:37:05 INFO - Using buildbot properties: 21:37:05 INFO - { 21:37:05 INFO - "project": "", 21:37:05 INFO - "product": "firefox", 21:37:05 INFO - "script_repo_revision": "production", 21:37:05 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 21:37:05 INFO - "repository": "", 21:37:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 21:37:05 INFO - "buildid": "20161003194901", 21:37:05 INFO - "pgo_build": "True", 21:37:05 INFO - "basedir": "/builds/slave/test", 21:37:05 INFO - "buildnumber": 178, 21:37:05 INFO - "slavename": "talos-linux64-ix-015", 21:37:05 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:37:05 INFO - "platform": "ubuntu64_hw", 21:37:05 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:37:05 INFO - "branch": "mozilla-beta", 21:37:05 INFO - "repo_path": "releases/mozilla-beta", 21:37:05 INFO - "revision": "ca2593daaa91f717e3ae65f049308309a31969a2", 21:37:05 INFO - "stage_platform": "linux64-pgo", 21:37:05 INFO - "builduid": "33b42db2aa034560befbe69970fd1c7e", 21:37:05 INFO - "slavebuilddir": "test" 21:37:05 INFO - } 21:37:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 21:37:05 INFO - Running post-action listener: _resource_record_post_action 21:37:05 INFO - [mozharness: 2016-10-04 04:37:05.508219Z] Finished read-buildbot-config step (success) 21:37:05 INFO - [mozharness: 2016-10-04 04:37:05.508309Z] Running download-and-extract step. 21:37:05 INFO - Running pre-action listener: _resource_record_pre_action 21:37:05 INFO - Running main action method: download_and_extract 21:37:05 INFO - mkdir: /builds/slave/test/build/tests 21:37:05 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')]} 21:37:05 INFO - https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:37:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:37:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:37:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:37:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:37:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:37:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:37:05 INFO - retry: Failed, sleeping 30 seconds before retrying 21:37:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:37:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:37:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:37:35 INFO - retry: Failed, sleeping 60 seconds before retrying 21:38:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:38:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:38:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:38:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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! 21:38:35 INFO - Caught exception: HTTP Error 404: Not Found 21:38:35 INFO - Caught exception: HTTP Error 404: Not Found 21:38:35 INFO - Caught exception: HTTP Error 404: Not Found 21:38:35 INFO - trying https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:38:35 INFO - Downloading https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:38:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:38:36 INFO - Downloaded 1327 bytes. 21:38:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:38:36 INFO - Using the following test package requirements: 21:38:36 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:38:36 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:38:36 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 21:38:36 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'jsshell-linux-x86_64.zip'], 21:38:36 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:38:36 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:38:36 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 21:38:36 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 21:38:36 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:38:36 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:38:36 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:38:36 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:38:36 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')]} 21:38:36 INFO - https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:38:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:38:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:38:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:38:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:38:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:38:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:38:36 INFO - retry: Failed, sleeping 30 seconds before retrying 21:39:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:39:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:39:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:39:06 INFO - retry: Failed, sleeping 60 seconds before retrying 21:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:40:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:40:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:40:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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! 21:40:06 INFO - Caught exception: HTTP Error 404: Not Found 21:40:06 INFO - Caught exception: HTTP Error 404: Not Found 21:40:06 INFO - Caught exception: HTTP Error 404: Not Found 21:40:06 INFO - trying https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:40:06 INFO - Downloading https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:40:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:40:07 INFO - Downloaded 21842099 bytes. 21:40:07 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'] 21:40:07 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 21:40:08 INFO - Return code: 0 21:40:08 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 21:40:08 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')]} 21:40:08 INFO - https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:40:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:40:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:40:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:40:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:40:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:40:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:40:08 INFO - retry: Failed, sleeping 30 seconds before retrying 21:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:40:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:40:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:40:38 INFO - retry: Failed, sleeping 60 seconds before retrying 21:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:41:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:41:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:41:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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! 21:41:38 INFO - Caught exception: HTTP Error 404: Not Found 21:41:38 INFO - Caught exception: HTTP Error 404: Not Found 21:41:38 INFO - Caught exception: HTTP Error 404: Not Found 21:41:38 INFO - trying https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:41:38 INFO - Downloading https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:41:39 INFO - Downloaded 21842099 bytes. 21:41:39 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'] 21:41:39 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 21:41:40 INFO - Return code: 0 21:41: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')]} 21:41:40 INFO - https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:41:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:41:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:41:40 INFO - retry: Failed, sleeping 30 seconds before retrying 21:42:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:42:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:42:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:42:10 INFO - retry: Failed, sleeping 60 seconds before retrying 21:43:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:43:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:43:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:43:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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! 21:43:10 INFO - Caught exception: HTTP Error 404: Not Found 21:43:10 INFO - Caught exception: HTTP Error 404: Not Found 21:43:10 INFO - Caught exception: HTTP Error 404: Not Found 21:43:10 INFO - trying https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:43:10 INFO - Downloading https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:43:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:43:11 INFO - Downloaded 14413740 bytes. 21:43:11 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'] 21:43:11 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 21:43:11 INFO - Return code: 0 21:43: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')]} 21:43:11 INFO - https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:43:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:43:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:43:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:43:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:43:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:43:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:43:11 INFO - retry: Failed, sleeping 30 seconds before retrying 21:43:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:43:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:43:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:43:41 INFO - retry: Failed, sleeping 60 seconds before retrying 21:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:44:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:44:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:44:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/chbHm9sHSUqB1RecC9rMsw/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! 21:44:41 INFO - Caught exception: HTTP Error 404: Not Found 21:44:41 INFO - Caught exception: HTTP Error 404: Not Found 21:44:41 INFO - Caught exception: HTTP Error 404: Not Found 21:44:41 INFO - trying https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:44:41 INFO - Downloading https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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 21:44:43 INFO - Downloaded 56781693 bytes. 21:44:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:44:43 INFO - mkdir: /builds/slave/test/properties 21:44:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:44:43 INFO - Writing to file /builds/slave/test/properties/build_url 21:44:43 INFO - Contents: 21:44:43 INFO - build_url:https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:44:43 INFO - Running post-action listener: _resource_record_post_action 21:44:43 INFO - Running post-action listener: set_extra_try_arguments 21:44:43 INFO - [mozharness: 2016-10-04 04:44:43.748571Z] Finished download-and-extract step (success) 21:44:43 INFO - [mozharness: 2016-10-04 04:44:43.748804Z] Running populate-webroot step. 21:44:43 INFO - Running pre-action listener: _resource_record_pre_action 21:44:43 INFO - Running main action method: populate_webroot 21:44:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:44:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:44:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:44:43 INFO - '--tppagecycles', 21:44:43 INFO - '7'], 21:44:43 INFO - 'tests': ['tsvgm']}}, 21:44:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:44:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:44:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:44:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:44:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:44:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:44:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['damp', 'tps']}, 21:44:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'tests': ['damp', 'tps']}, 21:44:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['dromaeo_dom']}, 21:44:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:44:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['basic_compositor_video']}, 21:44:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:44:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['a11yr', 21:44:43 INFO - 'ts_paint', 21:44:43 INFO - 'tpaint', 21:44:43 INFO - 'sessionrestore', 21:44:43 INFO - 'sessionrestore_no_auto_restore', 21:44:43 INFO - 'tabpaint']}, 21:44:43 INFO - 'other-e10s': {'tests': ['a11yr', 21:44:43 INFO - 'ts_paint', 21:44:43 INFO - 'tpaint', 21:44:43 INFO - 'sessionrestore', 21:44:43 INFO - 'sessionrestore_no_auto_restore', 21:44:43 INFO - 'tabpaint']}, 21:44:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:44:43 INFO - 'ts_paint', 21:44:43 INFO - 'tpaint', 21:44:43 INFO - 'sessionrestore', 21:44:43 INFO - 'sessionrestore_no_auto_restore', 21:44:43 INFO - 'tabpaint']}, 21:44:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:44:43 INFO - 'ts_paint', 21:44:43 INFO - 'tpaint', 21:44:43 INFO - 'sessionrestore', 21:44:43 INFO - 'sessionrestore_no_auto_restore', 21:44:43 INFO - 'tabpaint']}, 21:44:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['a11yr', 21:44:43 INFO - 'ts_paint', 21:44:43 INFO - 'tpaint', 21:44:43 INFO - 'sessionrestore', 21:44:43 INFO - 'sessionrestore_no_auto_restore', 21:44:43 INFO - 'tabpaint']}, 21:44:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['a11yr', 21:44:43 INFO - 'ts_paint', 21:44:43 INFO - 'tpaint', 21:44:43 INFO - 'sessionrestore', 21:44:43 INFO - 'sessionrestore_no_auto_restore', 21:44:43 INFO - 'tabpaint']}, 21:44:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['tsvgx', 21:44:43 INFO - 'tsvgr_opacity', 21:44:43 INFO - 'tart', 21:44:43 INFO - 'tscrollx', 21:44:43 INFO - 'cart']}, 21:44:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:44:43 INFO - 'tsvgr_opacity', 21:44:43 INFO - 'tart', 21:44:43 INFO - 'tscrollx', 21:44:43 INFO - 'cart']}, 21:44:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'talos_options': ['--disable-e10s'], 21:44:43 INFO - 'tests': ['tp5o']}, 21:44:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'tests': ['tp5o']}, 21:44:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'talos_options': ['--disable-e10s', 21:44:43 INFO - '--xperf_path', 21:44:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:44:43 INFO - 'tests': ['tp5n']}, 21:44:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:43 INFO - 'talos_options': ['--xperf_path', 21:44:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:44:43 INFO - 'tests': ['tp5n']}}, 21:44:43 INFO - 'talos.zip': {'path': '', 21:44:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:44:43 INFO - Running post-action listener: _resource_record_post_action 21:44:43 INFO - [mozharness: 2016-10-04 04:44:43.766522Z] Finished populate-webroot step (success) 21:44:43 INFO - [mozharness: 2016-10-04 04:44:43.766611Z] Running create-virtualenv step. 21:44:43 INFO - Running pre-action listener: _resource_record_pre_action 21:44:43 INFO - Running main action method: create_virtualenv 21:44:43 INFO - Creating virtualenv /builds/slave/test/build/venv 21:44:43 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 21:44:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:44:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:44:43 INFO - Using real prefix '/usr' 21:44:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:44:44 INFO - Installing distribute.............................................................................................................................................................................................done. 21:44:46 INFO - Installing pip.................done. 21:44:46 INFO - Return code: 0 21:44:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44: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', 'pip>=1.5'] in /builds/slave/test/build 21:44: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 pip>=1.5 21:44:46 INFO - Using env: {'DISPLAY': ':0', 21:44:46 INFO - 'HOME': '/home/cltbld', 21:44:46 INFO - 'LANG': 'en_US.UTF-8', 21:44:46 INFO - 'LANGUAGE': 'en_US:en', 21:44:46 INFO - 'LOGNAME': 'cltbld', 21:44:46 INFO - 'MAIL': '/var/mail/cltbld', 21:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:46 INFO - 'MOZ_NO_REMOTE': '1', 21:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:46 INFO - 'NO_EM_RESTART': '1', 21:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:46 INFO - 'PWD': '/builds/slave/test', 21:44:46 INFO - 'SHELL': '/bin/bash', 21:44:46 INFO - 'SHLVL': '1', 21:44:46 INFO - 'TERM': 'linux', 21:44:46 INFO - 'TMOUT': '86400', 21:44:46 INFO - 'USER': 'cltbld', 21:44:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:46 INFO - '_': '/tools/buildbot/bin/python'} 21:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:46 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 21:44:46 INFO - Cleaning up... 21:44:46 INFO - Return code: 0 21:44:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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', '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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44: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', 'psutil>=3.1.1'] in /builds/slave/test/build 21:44: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 psutil>=3.1.1 21:44:46 INFO - Using env: {'DISPLAY': ':0', 21:44:46 INFO - 'HOME': '/home/cltbld', 21:44:46 INFO - 'LANG': 'en_US.UTF-8', 21:44:46 INFO - 'LANGUAGE': 'en_US:en', 21:44:46 INFO - 'LOGNAME': 'cltbld', 21:44:46 INFO - 'MAIL': '/var/mail/cltbld', 21:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:46 INFO - 'MOZ_NO_REMOTE': '1', 21:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:46 INFO - 'NO_EM_RESTART': '1', 21:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:46 INFO - 'PWD': '/builds/slave/test', 21:44:46 INFO - 'SHELL': '/bin/bash', 21:44:46 INFO - 'SHLVL': '1', 21:44:46 INFO - 'TERM': 'linux', 21:44:46 INFO - 'TMOUT': '86400', 21:44:46 INFO - 'USER': 'cltbld', 21:44:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:46 INFO - '_': '/tools/buildbot/bin/python'} 21:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:46 INFO - Downloading/unpacking psutil>=3.1.1 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:44:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:44:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:44:48 INFO - Installing collected packages: psutil 21:44:48 INFO - Running setup.py install for psutil 21:44:48 INFO - building 'psutil._psutil_linux' extension 21:44:48 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 21:44:48 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 21:44:49 INFO - building 'psutil._psutil_posix' extension 21:44:49 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 21:44:49 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 21:44:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:44:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:44:49 INFO - Successfully installed psutil 21:44:49 INFO - Cleaning up... 21:44:49 INFO - Return code: 0 21:44:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:49 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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44:49 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 21:44:49 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 21:44:49 INFO - Using env: {'DISPLAY': ':0', 21:44:49 INFO - 'HOME': '/home/cltbld', 21:44:49 INFO - 'LANG': 'en_US.UTF-8', 21:44:49 INFO - 'LANGUAGE': 'en_US:en', 21:44:49 INFO - 'LOGNAME': 'cltbld', 21:44:49 INFO - 'MAIL': '/var/mail/cltbld', 21:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:49 INFO - 'MOZ_NO_REMOTE': '1', 21:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:49 INFO - 'NO_EM_RESTART': '1', 21:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:49 INFO - 'PWD': '/builds/slave/test', 21:44:49 INFO - 'SHELL': '/bin/bash', 21:44:49 INFO - 'SHLVL': '1', 21:44:49 INFO - 'TERM': 'linux', 21:44:49 INFO - 'TMOUT': '86400', 21:44:49 INFO - 'USER': 'cltbld', 21:44:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:49 INFO - '_': '/tools/buildbot/bin/python'} 21:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:44:49 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 21:44:49 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 21:44:49 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 21:44:49 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 21:44:49 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 21:44:49 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 21:44:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:44:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:44:51 INFO - Installing collected packages: mozsystemmonitor 21:44:51 INFO - Running setup.py install for mozsystemmonitor 21:44:51 INFO - Successfully installed mozsystemmonitor 21:44:51 INFO - Cleaning up... 21:44:51 INFO - Return code: 0 21:44:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:44:51 INFO - Using env: {'DISPLAY': ':0', 21:44:51 INFO - 'HOME': '/home/cltbld', 21:44:51 INFO - 'LANG': 'en_US.UTF-8', 21:44:51 INFO - 'LANGUAGE': 'en_US:en', 21:44:51 INFO - 'LOGNAME': 'cltbld', 21:44:51 INFO - 'MAIL': '/var/mail/cltbld', 21:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:51 INFO - 'MOZ_NO_REMOTE': '1', 21:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:51 INFO - 'NO_EM_RESTART': '1', 21:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:51 INFO - 'PWD': '/builds/slave/test', 21:44:51 INFO - 'SHELL': '/bin/bash', 21:44:51 INFO - 'SHLVL': '1', 21:44:51 INFO - 'TERM': 'linux', 21:44:51 INFO - 'TMOUT': '86400', 21:44:51 INFO - 'USER': 'cltbld', 21:44:51 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:51 INFO - '_': '/tools/buildbot/bin/python'} 21:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:51 INFO - Downloading/unpacking jsonschema==2.5.1 21:44:51 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 21:44:51 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 21:44:51 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 21:44:51 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 21:44:51 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 21:44:51 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 21:44:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:44:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:44:53 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 21:44:53 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 21:44:53 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 21:44:53 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 21:44:53 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 21:44:53 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 21:44:53 INFO - Downloading functools32-3.2.3-2.tar.gz 21:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:44:53 INFO - warning: no files found matching '*.txt' 21:44:53 INFO - no previously-included directories found matching 'build' 21:44:53 INFO - no previously-included directories found matching 'dist' 21:44:53 INFO - no previously-included directories found matching '.git*' 21:44:53 INFO - Installing collected packages: jsonschema, functools32 21:44:54 INFO - Running setup.py install for functools32 21:44:54 INFO - warning: no files found matching '*.txt' 21:44:54 INFO - no previously-included directories found matching 'build' 21:44:54 INFO - no previously-included directories found matching 'dist' 21:44:54 INFO - no previously-included directories found matching '.git*' 21:44:54 INFO - Successfully installed jsonschema functools32 21:44:54 INFO - Cleaning up... 21:44:54 INFO - Return code: 0 21:44:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:44:54 INFO - Using env: {'DISPLAY': ':0', 21:44:54 INFO - 'HOME': '/home/cltbld', 21:44:54 INFO - 'LANG': 'en_US.UTF-8', 21:44:54 INFO - 'LANGUAGE': 'en_US:en', 21:44:54 INFO - 'LOGNAME': 'cltbld', 21:44:54 INFO - 'MAIL': '/var/mail/cltbld', 21:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:54 INFO - 'MOZ_NO_REMOTE': '1', 21:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:54 INFO - 'NO_EM_RESTART': '1', 21:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:54 INFO - 'PWD': '/builds/slave/test', 21:44:54 INFO - 'SHELL': '/bin/bash', 21:44:54 INFO - 'SHLVL': '1', 21:44:54 INFO - 'TERM': 'linux', 21:44:54 INFO - 'TMOUT': '86400', 21:44:54 INFO - 'USER': 'cltbld', 21:44:54 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:54 INFO - '_': '/tools/buildbot/bin/python'} 21:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:44:54 INFO - Cleaning up... 21:44:54 INFO - Return code: 0 21:44:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:44:54 INFO - Using env: {'DISPLAY': ':0', 21:44:54 INFO - 'HOME': '/home/cltbld', 21:44:54 INFO - 'LANG': 'en_US.UTF-8', 21:44:54 INFO - 'LANGUAGE': 'en_US:en', 21:44:54 INFO - 'LOGNAME': 'cltbld', 21:44:54 INFO - 'MAIL': '/var/mail/cltbld', 21:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:54 INFO - 'MOZ_NO_REMOTE': '1', 21:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:54 INFO - 'NO_EM_RESTART': '1', 21:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:54 INFO - 'PWD': '/builds/slave/test', 21:44:54 INFO - 'SHELL': '/bin/bash', 21:44:54 INFO - 'SHLVL': '1', 21:44:54 INFO - 'TERM': 'linux', 21:44:54 INFO - 'TMOUT': '86400', 21:44:54 INFO - 'USER': 'cltbld', 21:44:54 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:54 INFO - '_': '/tools/buildbot/bin/python'} 21:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:54 INFO - Downloading/unpacking blobuploader==1.2.4 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44: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 21:44:56 INFO - Downloading blobuploader-1.2.4.tar.gz 21:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:44:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:44:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 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 21:44:56 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 21:44:57 INFO - Downloading docopt-0.6.1.tar.gz 21:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:44:57 INFO - Installing collected packages: blobuploader, requests, docopt 21:44:57 INFO - Running setup.py install for blobuploader 21:44:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:44:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:44:57 INFO - Running setup.py install for requests 21:44:57 INFO - Running setup.py install for docopt 21:44:57 INFO - Successfully installed blobuploader requests docopt 21:44:57 INFO - Cleaning up... 21:44:57 INFO - Return code: 0 21:44:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:44: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')]} 21:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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')]} 21:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:57 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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:44:57 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 21:44:57 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 21:44:57 INFO - Using env: {'DISPLAY': ':0', 21:44:57 INFO - 'HOME': '/home/cltbld', 21:44:57 INFO - 'LANG': 'en_US.UTF-8', 21:44:57 INFO - 'LANGUAGE': 'en_US:en', 21:44:57 INFO - 'LOGNAME': 'cltbld', 21:44:57 INFO - 'MAIL': '/var/mail/cltbld', 21:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:57 INFO - 'MOZ_NO_REMOTE': '1', 21:44:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:57 INFO - 'NO_EM_RESTART': '1', 21:44:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:44:57 INFO - 'PWD': '/builds/slave/test', 21:44:57 INFO - 'SHELL': '/bin/bash', 21:44:57 INFO - 'SHLVL': '1', 21:44:57 INFO - 'TERM': 'linux', 21:44:57 INFO - 'TMOUT': '86400', 21:44:57 INFO - 'USER': 'cltbld', 21:44:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:57 INFO - '_': '/tools/buildbot/bin/python'} 21:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:44:58 INFO - Running setup.py (path:/tmp/pip-5GwK9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:44:58 INFO - Running setup.py (path:/tmp/pip-iE9CKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:44:58 INFO - Running setup.py (path:/tmp/pip-12ZgyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:44:58 INFO - Running setup.py (path:/tmp/pip-GmVdqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:44:58 INFO - Running setup.py (path:/tmp/pip-4mRgo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:44:58 INFO - Running setup.py (path:/tmp/pip-zsdlTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:44:58 INFO - Running setup.py (path:/tmp/pip-r0XdqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:44:58 INFO - Running setup.py (path:/tmp/pip-mQLpVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:44:58 INFO - Running setup.py (path:/tmp/pip-NO_mE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:44:58 INFO - Running setup.py (path:/tmp/pip-TsCE_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:44:58 INFO - Running setup.py (path:/tmp/pip-SRB7ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:44:58 INFO - Running setup.py (path:/tmp/pip-qD86BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:44:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:44:58 INFO - Running setup.py (path:/tmp/pip-NsoCBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:44:59 INFO - Running setup.py (path:/tmp/pip-uQ_VKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:44:59 INFO - Running setup.py (path:/tmp/pip-xrVi5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:44:59 INFO - Running setup.py (path:/tmp/pip-u8ndO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:44:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:44:59 INFO - Running setup.py (path:/tmp/pip-CmISWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:44:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:44:59 INFO - Running setup.py install for manifestparser 21:44:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:44:59 INFO - Running setup.py install for mozcrash 21:44:59 INFO - Running setup.py install for mozdebug 21:44:59 INFO - Running setup.py install for mozdevice 21:44:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:44:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:44:59 INFO - Running setup.py install for mozfile 21:44:59 INFO - Running setup.py install for mozhttpd 21:44:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:44:59 INFO - Running setup.py install for mozinfo 21:45:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Running setup.py install for mozInstall 21:45:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Running setup.py install for mozleak 21:45:00 INFO - Running setup.py install for mozlog 21:45:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Running setup.py install for moznetwork 21:45:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Running setup.py install for mozprocess 21:45:00 INFO - Running setup.py install for mozprofile 21:45:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Running setup.py install for mozrunner 21:45:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:45:00 INFO - Running setup.py install for mozscreenshot 21:45:00 INFO - Running setup.py install for moztest 21:45:01 INFO - Running setup.py install for mozversion 21:45:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:45:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:45:01 INFO - Cleaning up... 21:45:01 INFO - Return code: 0 21:45:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:45: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')]} 21:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45: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')]} 21:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:01 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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:45:01 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 21:45:01 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 21:45:01 INFO - Using env: {'DISPLAY': ':0', 21:45:01 INFO - 'HOME': '/home/cltbld', 21:45:01 INFO - 'LANG': 'en_US.UTF-8', 21:45:01 INFO - 'LANGUAGE': 'en_US:en', 21:45:01 INFO - 'LOGNAME': 'cltbld', 21:45:01 INFO - 'MAIL': '/var/mail/cltbld', 21:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:01 INFO - 'MOZ_NO_REMOTE': '1', 21:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:01 INFO - 'NO_EM_RESTART': '1', 21:45:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:45:01 INFO - 'PWD': '/builds/slave/test', 21:45:01 INFO - 'SHELL': '/bin/bash', 21:45:01 INFO - 'SHLVL': '1', 21:45:01 INFO - 'TERM': 'linux', 21:45:01 INFO - 'TMOUT': '86400', 21:45:01 INFO - 'USER': 'cltbld', 21:45:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:01 INFO - '_': '/tools/buildbot/bin/python'} 21:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:45:01 INFO - Running setup.py (path:/tmp/pip-ZQF3IG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:45:01 INFO - Running setup.py (path:/tmp/pip-jHk39U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:45:01 INFO - Running setup.py (path:/tmp/pip-qJtHxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:45:01 INFO - Running setup.py (path:/tmp/pip-tq62wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:45:01 INFO - Running setup.py (path:/tmp/pip-4XUYwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:45:01 INFO - Running setup.py (path:/tmp/pip-a3PyrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:45:01 INFO - Running setup.py (path:/tmp/pip-hv2gH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:45:01 INFO - Running setup.py (path:/tmp/pip-evHSiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:45:01 INFO - Running setup.py (path:/tmp/pip-AMLPP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:45:01 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)) 21:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:45:01 INFO - Running setup.py (path:/tmp/pip-xUhiG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:45:02 INFO - Running setup.py (path:/tmp/pip-z8EFXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:45:02 INFO - Running setup.py (path:/tmp/pip-87tqLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:45:02 INFO - Running setup.py (path:/tmp/pip-bSl9Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:45:02 INFO - Running setup.py (path:/tmp/pip-C95CC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:45:02 INFO - Running setup.py (path:/tmp/pip-tvw5Kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:45:02 INFO - Running setup.py (path:/tmp/pip-EJdagc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:45:02 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)) 21:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:45:02 INFO - Running setup.py (path:/tmp/pip-WATlx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:45:02 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)) 21:45:02 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)) 21:45:02 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)) 21:45:02 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)) 21:45:02 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)) 21:45:02 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)) 21:45:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:45:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:45:04 INFO - Downloading blessings-1.6.tar.gz 21:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:45:04 INFO - Installing collected packages: blessings 21:45:04 INFO - Running setup.py install for blessings 21:45:04 INFO - Successfully installed blessings 21:45:04 INFO - Cleaning up... 21:45:04 INFO - Return code: 0 21:45:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:45:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:45:04 INFO - Reading from file tmpfile_stdout 21:45:04 INFO - Current package versions: 21:45:04 INFO - argparse == 1.2.1 21:45:04 INFO - blessings == 1.6 21:45:04 INFO - blobuploader == 1.2.4 21:45:04 INFO - docopt == 0.6.1 21:45:04 INFO - functools32 == 3.2.3-2 21:45:04 INFO - jsonschema == 2.5.1 21:45:04 INFO - manifestparser == 1.1 21:45:04 INFO - mozInstall == 1.12 21:45:04 INFO - mozcrash == 0.17 21:45:04 INFO - mozdebug == 0.1 21:45:04 INFO - mozdevice == 0.48 21:45:04 INFO - mozfile == 1.2 21:45:04 INFO - mozhttpd == 0.7 21:45:04 INFO - mozinfo == 0.9 21:45:04 INFO - mozleak == 0.1 21:45:04 INFO - mozlog == 3.3 21:45:04 INFO - moznetwork == 0.27 21:45:04 INFO - mozprocess == 0.23 21:45:04 INFO - mozprofile == 0.28 21:45:04 INFO - mozrunner == 6.12 21:45:04 INFO - mozscreenshot == 0.1 21:45:04 INFO - mozsystemmonitor == 0.3 21:45:04 INFO - moztest == 0.7 21:45:04 INFO - mozversion == 1.4 21:45:04 INFO - psutil == 3.1.1 21:45:04 INFO - requests == 1.2.3 21:45:04 INFO - wsgiref == 0.1.2 21:45:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:45:04 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')]} 21:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:04 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')]} 21:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:04 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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:45:04 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 21:45:04 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 21:45:04 INFO - Using env: {'DISPLAY': ':0', 21:45:04 INFO - 'HOME': '/home/cltbld', 21:45:04 INFO - 'LANG': 'en_US.UTF-8', 21:45:04 INFO - 'LANGUAGE': 'en_US:en', 21:45:04 INFO - 'LOGNAME': 'cltbld', 21:45:04 INFO - 'MAIL': '/var/mail/cltbld', 21:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:04 INFO - 'MOZ_NO_REMOTE': '1', 21:45:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:04 INFO - 'NO_EM_RESTART': '1', 21:45:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:45:04 INFO - 'PWD': '/builds/slave/test', 21:45:04 INFO - 'SHELL': '/bin/bash', 21:45:04 INFO - 'SHLVL': '1', 21:45:04 INFO - 'TERM': 'linux', 21:45:04 INFO - 'TMOUT': '86400', 21:45:04 INFO - 'USER': 'cltbld', 21:45:04 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:04 INFO - '_': '/tools/buildbot/bin/python'} 21:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 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)) 21:45:05 INFO - Cleaning up... 21:45:05 INFO - Return code: 0 21:45:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:45:05 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')]} 21:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:05 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')]} 21:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:05 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 0xc53030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf14180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, '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': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', '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 21:45:05 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 21:45:05 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 21:45:05 INFO - Using env: {'DISPLAY': ':0', 21:45:05 INFO - 'HOME': '/home/cltbld', 21:45:05 INFO - 'LANG': 'en_US.UTF-8', 21:45:05 INFO - 'LANGUAGE': 'en_US:en', 21:45:05 INFO - 'LOGNAME': 'cltbld', 21:45:05 INFO - 'MAIL': '/var/mail/cltbld', 21:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:05 INFO - 'MOZ_NO_REMOTE': '1', 21:45:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:05 INFO - 'NO_EM_RESTART': '1', 21:45:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:45:05 INFO - 'PWD': '/builds/slave/test', 21:45:05 INFO - 'SHELL': '/bin/bash', 21:45:05 INFO - 'SHLVL': '1', 21:45:05 INFO - 'TERM': 'linux', 21:45:05 INFO - 'TMOUT': '86400', 21:45:05 INFO - 'USER': 'cltbld', 21:45:05 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:05 INFO - '_': '/tools/buildbot/bin/python'} 21:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:45:05 INFO - Cleaning up... 21:45:05 INFO - Return code: 0 21:45:05 INFO - Running post-action listener: _resource_record_post_action 21:45:05 INFO - Running post-action listener: _start_resource_monitoring 21:45:05 INFO - Starting resource monitoring. 21:45:05 INFO - [mozharness: 2016-10-04 04:45:05.392753Z] Finished create-virtualenv step (success) 21:45:05 INFO - [mozharness: 2016-10-04 04:45:05.393232Z] Running install step. 21:45:05 INFO - Running pre-action listener: _resource_record_pre_action 21:45:05 INFO - Running main action method: install 21:45:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:45:05 INFO - Reading from file tmpfile_stdout 21:45:05 INFO - Detecting whether we're running mozinstall >=1.0... 21:45:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:45:05 INFO - Reading from file tmpfile_stdout 21:45:05 INFO - Output received: 21:45:05 INFO - Usage: mozinstall [options] installer 21:45:05 INFO - Options: 21:45:05 INFO - -h, --help show this help message and exit 21:45:05 INFO - -d DEST, --destination=DEST 21:45:05 INFO - Directory to install application into. [default: 21:45:05 INFO - "/builds/slave/test"] 21:45:05 INFO - --app=APP Application being installed. [default: firefox] 21:45:05 INFO - mkdir: /builds/slave/test/build/application 21:45:05 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'] 21:45:05 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 21:45:19 INFO - Reading from file tmpfile_stdout 21:45:19 INFO - Output received: 21:45:19 INFO - /builds/slave/test/build/application/firefox/firefox 21:45:19 INFO - Running post-action listener: _resource_record_post_action 21:45:19 INFO - [mozharness: 2016-10-04 04:45:19.750559Z] Finished install step (success) 21:45:19 INFO - [mozharness: 2016-10-04 04:45:19.750837Z] Running run-tests step. 21:45:19 INFO - Running pre-action listener: _resource_record_pre_action 21:45:19 INFO - Running main action method: run_tests 21:45:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:45:19 INFO - Python 2.7.3 21:45:19 INFO - Return code: 0 21:45:19 INFO - grabbing minidump binary from tooltool 21:45:19 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')]} 21:45:19 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 0xf123b0>, '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 0xf132b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf13740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:45:19 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 21:45:19 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 21:45:19 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 21:45:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:45:19 INFO - Return code: 0 21:45:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:45:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:45:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:45:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:45:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:45:19 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/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 21:45:19 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/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --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 21:45:19 INFO - Using env: {'DISPLAY': ':0', 21:45:19 INFO - 'HOME': '/home/cltbld', 21:45:19 INFO - 'LANG': 'en_US.UTF-8', 21:45:19 INFO - 'LANGUAGE': 'en_US:en', 21:45:19 INFO - 'LOGNAME': 'cltbld', 21:45:19 INFO - 'MAIL': '/var/mail/cltbld', 21:45:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:45:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:19 INFO - 'MOZ_NO_REMOTE': '1', 21:45:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:45:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:19 INFO - 'NO_EM_RESTART': '1', 21:45:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:45:19 INFO - 'PWD': '/builds/slave/test', 21:45:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:45:19 INFO - 'SHELL': '/bin/bash', 21:45:19 INFO - 'SHLVL': '1', 21:45:19 INFO - 'TERM': 'linux', 21:45:19 INFO - 'TMOUT': '86400', 21:45:19 INFO - 'USER': 'cltbld', 21:45:19 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827', 21:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:19 INFO - '_': '/tools/buildbot/bin/python'} 21:45:19 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/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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 21:45:20 INFO - mozversion application_buildid: 20161003194901 21:45:20 INFO - mozversion application_changeset: ca2593daaa91f717e3ae65f049308309a31969a2 21:45:20 INFO - mozversion application_display_name: Firefox 21:45:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:45:20 INFO - mozversion application_name: Firefox 21:45:20 INFO - mozversion application_remotingname: firefox 21:45:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:45:20 INFO - mozversion application_vendor: Mozilla 21:45:20 INFO - mozversion application_version: 50.0 21:45:20 INFO - mozversion platform_buildid: 20161003194901 21:45:20 INFO - mozversion platform_changeset: ca2593daaa91f717e3ae65f049308309a31969a2 21:45:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:45:20 INFO - mozversion platform_version: 50.0 21:45:20 INFO - using testdate: 1475556320 21:45:20 INFO - actual date: 1475556320 21:45:20 INFO - starting webserver on 'localhost:43219' 21:45:20 INFO - SUITE-START | Running 2 tests 21:45:20 INFO - TEST-START | dromaeo_css 21:45:20 INFO - operating with platform_type : linux_ 21:45:20 INFO - Initialising browser for dromaeo_css test... 21:45:20 INFO - TEST-INFO | started process 8808 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZMCN4W/profile http://localhost:43219/getInfo.html) 21:45:23 INFO - PROCESS | 8808 | __metrics Screen width/height:1600/1200 21:45:23 INFO - PROCESS | 8808 | colorDepth:24 21:45:23 INFO - PROCESS | 8808 | Browser inner width/height: 1024/697 21:45:23 INFO - PROCESS | 8808 | __metrics 21:45:24 INFO - PROCESS | 8808 | 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" 21:45:24 INFO - PROCESS | 8808 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:45:24 INFO - PROCESS | 8808 | console.error: PushService: 21:45:24 INFO - PROCESS | 8808 | stateChangeProcessEnqueue: Error transitioning state 21:45:24 INFO - PROCESS | 8808 | UnknownError 21:45:25 INFO - TEST-INFO | 8808: exit 0 21:45:25 INFO - Browser initialized. 21:45:25 INFO - Running cycle 1/1 for dromaeo_css test... 21:45:25 INFO - TEST-INFO | started process 8893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZMCN4W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:45:37 INFO - PROCESS | 8893 | RSS: Main: 144076800 21:45:37 INFO - PROCESS | 8893 | 21:46:28 INFO - PROCESS | 8893 | 1475556388835 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] 21:48:36 INFO - PROCESS | 8893 | Cycle 1(1): loaded http://localhost:43219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43219/tests/dromaeo/cssquery-ext.html) 21:48:37 INFO - PROCESS | 8893 | RSS: Main: 263532544 21:48:37 INFO - PROCESS | 8893 | 21:51:40 INFO - PROCESS | 8893 | Cycle 1(1): loaded http://localhost:43219/tests/dromaeo/cssquery-ext.html (next: http://localhost:43219/tests/dromaeo/cssquery-jquery.html) 21:51:40 INFO - PROCESS | 8893 | RSS: Main: 233951232 21:51:40 INFO - PROCESS | 8893 | 21:54:42 INFO - PROCESS | 8893 | Cycle 1(1): loaded http://localhost:43219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43219/tests/dromaeo/cssquery-mootools.html) 21:54:42 INFO - PROCESS | 8893 | RSS: Main: 206868480 21:54:42 INFO - PROCESS | 8893 | 21:57:44 INFO - PROCESS | 8893 | Cycle 1(1): loaded http://localhost:43219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43219/tests/dromaeo/cssquery-prototype.html) 21:57:45 INFO - PROCESS | 8893 | RSS: Main: 174952448 21:57:45 INFO - PROCESS | 8893 | 22:00:47 INFO - PROCESS | 8893 | Cycle 1(1): loaded http://localhost:43219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43219/tests/dromaeo/cssquery-yui.html) 22:00:48 INFO - PROCESS | 8893 | RSS: Main: 177262592 22:00:48 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | Cycle 1(1): loaded http://localhost:43219/tests/dromaeo/cssquery-yui.html (next: http://localhost:43219/tests/dromaeo/cssquery-dojo.html) 22:03:51 INFO - PROCESS | 8893 | RSS: Main: 263098368 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | __start_tp_report 22:03:51 INFO - PROCESS | 8893 | _x_x_mozilla_page_load 22:03:51 INFO - PROCESS | 8893 | _x_x_mozilla_page_load_details 22:03:51 INFO - PROCESS | 8893 | |i|pagename|runs| 22:03:51 INFO - PROCESS | 8893 | |0;dojo.html;3158.8262645554514;3188.171075520365;3197.2326641606014;3216.533602627619;3236.676332366762;5430.294061771974;5528.087865502197;5537.75080121395;5538.169274608806;5543.113101903697;5615.7472913718875;5660.575456840733;5671.120976248678;5677.744501497432;5703.40114288;5111.284420181178;5128.1282182029145;5187.144121219994;5189.896202075956;5206.036883176605;5078.669886457387;5196.844094677166;5220.869478263055;5221.686698798066;5227.581793456533;5043.420006699222;5045.621578381618;5062.924056139161;5067.0879241736475;5100.107481190796;5058.140116180258;5094.872628184306;5119.436861945183;5174.586033117342;5206.271122042917;5002.449730529639;5061.468545802712;5114.667546609496;5118.436971933084;5131.563817075553;5418.431064738185;5443.428440013781;5459.153831156177;5462.044142275086;5466.508014278734;5916.142159386908;5926.525877929756;5937.287525496925;5985.91021134679;6016.639001659914;5901;6037.879242415171;6038.396160383971;6055.394460553954;6056.788012419544;5499.450054994509;5540.667559946335;5542.057850165482;5542.750576224039;5568.916466253009;6081.756729730761;6082.817515474543;6093.75624974996;6103.786367477206;6107.755689772448;5747.568932330092;5773.971130144407;5858.06270996647;5860;5861.3845546217035;5861.20873682056;5937.2578427696535;5937.317208520989;5963.850903727354;5970.582059255898;5885.5585831062845;5889.734961926723;5897.321807992136;5898.557608179403;5902.763889444374;5801.129830525455;5908.822735317947;5911.379305172981;5914.881702366015;5937.198478205473;4586.174488592019;4595.058013107305;4595.586397224199;4645.233536466513;4673.182193116191;4081.0817566047403;4103.528094269138;4106.856260030884;4144.254034273859;4147.460830092129;5648.943510564924;5796.159556864315;5800.796972105956;5802.709864506758;5808.128780682931;6002.759889604367;6044.274687037584;6045.667488288186;6119.472088496418;6119.746288422706;5795.8551036224435;5798.478136967693;5808.30300363959;5811.622244554091;5842.649441033466;4970.776315065831;5146.150885103916;5148.588112950955;5152.536271735562;5156.484351564888;6180.5673602848265;6238.719257633417;6239.594426362272;6342.714577844008;6355.968220158962;6017.157597936205;6052.788152414644;6053.303870055;6066.241719785027;6068.120122582289;6140.534923917628;6287.874242515032;6332.240131184288;6332.3034466207755;6335.461485773761;5834.533237341087;5932.466078052996;5939.406059394199;6005.309389420097;6035.215421995113;6064.241969753781;6068.848278793065;6070.2412198475195;6076.90884636722;6082.118092876596;5789.305283365855;5959.374265702038;5967;5970.791022314198;5972.25346831646;5766.682832444256;5821.970890145692;5824.330202026821;5903.291605007426;5904.498117660047;5736.971315143398;5786.392428794916;5787;5790.305163380422;5793.131030345482;5826.213461182686;5896.557758168069;5900.1149827526215;5902.2622172228475;5914.142449344907;5524.226608274925;5739.770409183753;5772.56705747079;5784.334801497882;5839.065749480063 22:03:51 INFO - PROCESS | 8893 | |1;ext.html;588.5968111843388;639.2999665366424;668.3884245914986;674.302097329264;678.0473434824074;35042.2991540169;38229.61770382295;38411.80794096032;38420.23159536815;38468.038299042506;46716;47648;47807.282890756665;52788;52850;9233.676821311255;9243.260539156885;9301.767455813591;9311.76720581984;9312.34813563051;8612.612432440555;9218.585163667618;9380.390274632162;11384;11589.130815188852;11455.713607159805;11491.482883270231;11516.560429946257;11522.539098436095;11522.596709115183;11328.320300781937;11379.317240965527;11380.943095284554;11385.487653055632;11387.25982811123;11167.274127181707;11228.382438965935;11230.943845280803;11236.21346505745;11242.66272011841;46090.769546152056;46201;46371.53628463706;46390.768046159894;46554;3913.0804260998643;3937.0118040478096;4078.1028173801715;4080.510338759338;4450.221211288044;35922.461163082575;36034.819825900966;36604.81697591495;36622.633773662455;36942.63057369419;4516.503184649686;4575.633949284049;4578.267477203632;4601.309803529464;4692.953070469286;361443;363990;364650;365121;366000;38807.61192388096;66055;66258;66286.67996620192;66353;39842.60157398389;41993.79003105026;41997;42024.36963445551;42029.36955945663;17644;17895.10524473771;17899.552511187063;17910.731339029924;17911;14968.775468367765;15074.170920599254;15077.623059423602;15088.321025553876;15120.848791511944;8749.912500875038;9152.816943661222;9563.808723825485;9574.617015319449;9739.659111931049;3526.501236974276;3581.28374325136;3620.0768803954825;3646.34365814156;3653.5798383186275;996.4569309726216;1027.635189507729;1047.2773786087575;1082.5940272397852;1086.46220121039;35101;37089;37347;38140;39570;1147.6270212181075;1149.1898211760688;1204.476052916984;1225.0261042471257;1299.9285039322929;18123.637527249382;18571.628567428845;18595;18691.81308186928;18744.062796860104;604351;613109;614023;616663;618091;64623;64857.35142648513;64922.67538662277;65047;65121.67439162869;1356.559118286541;1396.4763213794827;1443.3072125379877;1467.449706360115;1501.9699606007819;31447.84276078605;36624.63375366212;36805.079873003386;36863.8156809225;37154.8142259287;55130.44869551253;55808.44191558195;56052.439475606356;56069;56117.71941140268;437717;439821;442129;443098;444082;27825.16524504268;29979.250518737204;31186.844065779525;31277;32221.194470138435;51232;52392;52481.47518524766;52599;52993.20510192273;43538.43704602813;44476.777616111714;44663.55336446594;45582.77208613935;46518.767406162755;43563;44270;44655.55344446514;45457.54542454533;45786.54213457823;43816.34275485934;43944;44012;44437.77781111074;45434;37632.623673762915;39065;39205.80397097996;40025.79987100046;40308;29676.703232967393;30111.849440752656;30238.54642180325;30545.389092218476;30739.538906916863 22:03:51 INFO - PROCESS | 8893 | |2;jquery.html;415.5574313356277;424.4119418060331;434.5610932957713;438.20903269598364;447.10132633406863;18949;20394.592108157845;20784.792152078473;21244.893775531138;21524.677129843025;20345;20412.89793551034;20713.482162945933;20886.791132088674;20892.164313427482;5771.192033115367;6006.609570377933;6010.789622363218;6014.338422773491;6015.6992150392525;5809.3900140485275;5862.179294898696;6034.5172386209215;6066.241719785027;6170.938290617081;5988.580799344048;6149.477294429978;6149.508039356863;6156.692165391735;6166.38336166382;6126.7855625053135;6184.536159788011;6198.535109866733;6216.782412615582;6226.3462336454695;5880.26496687914;5980.342162362137;6037.45662890342;6041.426064523863;6042;15362;15448.922755386264;15486;15549.922250388789;15591;2470.3824043989002;2498.9875050624814;2658.8404695718127;2715.660542432196;2893.4068515954177;10695;10902.345859248471;10957.452127393679;11117.444127793578;11121.72195695106;1923.7595300587427;2035.4097311779567;2041.7549894012814;2103.905324260397;2121.331780489141;83387;85664.57167714184;87467.12532874718;88630.55684721535;89394;11663.533458661559;11936.701582460335;12036.699082522831;12043.87956120445;12086.637400877988;17250.65498690019;17261.482155535356;17269.13654317304;17289.13554322279;17305.221265043103;8943.971443284106;8991.865122023044;9035.322350823715;9197.908020919838;9306.162445379812;8727.345449091343;8774.824503509893;8840.469571825583;8985.685501007563;9098.636054557875;5093.108705976513;5199.480051994846;5217.6347655664495;5271.976096132565;5309.707966061904;2002.6982461399916;2033.6136134134467;2057.753069631654;2069.4102180878376;2087.77034526202;971.4414211828228;1024.4058446101244;1048.9318194317345;1080.8054550180887;1081.2431298091367;19054.333098341493;20685.793142068687;20908.37274881756;20910.790892090885;21121.68317475239;1029.7785976015193;1048.826943554305;1062.245805478119;1074.7259448840496;1128.785530749155;10811.567537298577;11812.645620631223;11866.88133118675;11873.109516786102;11897.226680265756;522613;533490;543436;560554;560923;91464.54267728752;91941.54029229812;94241.5287923556;95073;95608.52195739116;1045.5869931377063;1069.7964789611688;1080.2762149359949;1092.4919912240846;1093.9507722152523;17654.823451765318;17716;17793.644127117128;18457.35399261073;18692.25230990745;43438.782806085765;43847.34228986631;44910.55089449064;45191.77404112958;46197.53802461932;62185.37814621796;62236.68881655744;62755.05867411901;62962;63618.68190659017;8854.424462410181;9015.954920225357;9087.772805679911;9346.906530934604;9484.525773711424;11235.494402751732;13005.804912926504;13282.53511127106;13375.331233438485;13381.799273011107;11634.6509604712;11722.531098756295;11730.944215020689;11822.467988940347;11825.822612660644;10693.14454843595;11250.312453132095;11829.822552661544;11889.82165267539;11921.82117268259;7140.07179066718;11767.470463829319;11818.231814932002;12374.81437778452;12898.097133200587;7973.681052757825;8226.136255693065;8918.10818918103;9127.452352858849;9176.852893388326;11739.178257521891;12013.81979270329;12023.75952480963;12079.75840483203;12270.509179633073 22:03:51 INFO - PROCESS | 8893 | |3;mootools.html;1239.6652903716003;1529.3347393883657;1541.2216830500595;1740.8433241008306;1877.8779379340342;8072.71745488908;8718.21536061755;9118.817623647523;9149.085091490848;9471;9181.449113053204;9239.722808315744;9243.6302547898;9252.491112988782;9254.12085851843;2393.5571919194963;2420.6732091167733;2423.09134074722;2426.890789914449;2429.161939130997;2374.798142157919;2387.8209134315;2448.400141965221;2450.6936632920883;2459.213051823417;2383.594788885885;2385.558671645751;2391.8564886106888;2416.0094361311867;2416.903323867043;2366.7988221001237;2376.251480783548;2380.9761902381015;2383.892724827387;2401.6877805885297;2356.7289761677457;2358.634411666177;2365.6924599802087;2365.7989070928993;2367.7158740951195;8725.520096394695;9157.267418606496;9179.862302065474;9238.769030774218;9250.676226332045;5244.292020577212;5518.475744804237;5519.199716041152;5519.337679478488;5520.806771762969;3630.1832087780303;3659.323025240339;3678.043708635774;3688.8708895188806;3722;1255.8869701726796;1261.2495565138727;1268.1313300389263;1285.1710646632869;1291.5092264939262;12925.935370323183;12970.416331265116;13003;13008.674783130498;13015.674608134777;4283.807228674717;4538.568835960578;4615.9076818463445;4716.3868697069165;4760.190767569452;9779.070988256104;9813.362131461432;9847.113759761656;9849.162821160138;9851;5243.68537887728;5246.0557099721655;5252.395974462909;5260.815871444558;5261.263423120689;4527.705699129546;4882.804687812519;4939.678920870132;4987.750612469362;5025.597952163818;3931.5282166140246;3999.6600288975765;4029.879103626896;4076.184763047402;4099.478820525355;1187.145255416099;1199.8920097191294;1201.5193922431097;1205.7588482303574;1212.9150959432754;1050.4117694091333;1070.9196810239107;1074.6399956014666;1077.1867240233573;1086.0497065068066;6127.509799216052;6156.846078847975;6172.382761723792;6243.937560624427;6482.286948435668;479.8296604705349;481.1531704200632;492.81026804680454;507.6015327967528;508.64140780749483;3927.489426374553;3950.367941129463;4013.0974723475574;4017.196560687874;4235.512916014698;11261;11786.174967752346;11794.056475482112;11811.114166437552;11825.349605771593;11716.414179291001;11735.119866010084;11736.823947640793;11747.941260293645;11775.823362649568;8638.222559969507;8678.39251252419;8831.91168088311;8837.867431988525;8862.291016718647;6323.588966717149;6352.396522330371;6372.07604897278;6450.193725784277;6488.837779055561;10555.577776888838;10563.419011954109;10659.20055995819;10711;10845.403502807105;10796.67609971702;10898.945505272422;10937.890621093688;11006.834897476385;11059.281146725438;7019.2629773873;7439.888401673871;7688.846223075395;7779.533228006338;7916.287534121957;9216.815663686823;9293.767655808659;9294.163525282487;9295.442273463614;9297;8255.917440825515;8284.212999765012;8385.95807020961;8426.747197584082;8483.95758021206;7987.760367188994;8106.351491880518;8206.179382061746;8212.137725538732;8416.653335466295;7363.77908662741;7471.140818805907;7475.551466912003;7523;7553.282438168365;5684.687342196054;5697.800576979786;5769.134629805562;5775.624584402;5873.647581144974;5515.117581186991;5517.475839795213;5656.830295091154;5736.139579063174;5779.36426993022 22:03:51 INFO - PROCESS | 8893 | |4;prototype.html;348.7070860477199;353.9539859818224;355.3922792025636;360.54751287134644;363.8744633101579;5516.7517461714215;5517.420670829561;5526.41972592878;5545.639533430324;5551.833444996657;5442.958890416094;5472.808451704191;5497.642653227537;5511.173324001402;5515.503604675579;3860.3823388257883;4078.7770664987506;4340.97829510852;4360.934585981213;4366.497852746943;4346.9999999999845;4372;4377.430933978579;4386.429764130675;4392.824287028515;4384.8026838792175;4393.340998850182;4398.780060996954;4404.933925991113;4414.624756895668;4178.8537401191115;4365.825366985317;4389.188000219953;4399.186150562125;4401.60385565299;3892.4355968384425;3900.083480382108;3902.804859757029;3905.4141878718137;3912.2371137628174;5326.627136100475;5329.573634109262;5377.596680248997;5405.324334458192;5418.509909774804;4896.975515122437;4926.871436637725;4976.278439626234;5033.647644664875;5093.312402825608;5658.292713410824;5704.372519022904;5715.6284841485585;5723.971380143073;5755.654660720328;4353.238183317907;4361.193179261847;4381.75900325482;4385.574688226339;4389.714668546534;5891.587588868781;5977.894089593439;5994.700264986777;6028.638281703112;6059.878802423927;4345.587169218919;4400.559944005574;4428.778561071934;4459.910801783946;4464.508904020555;4532.818687252539;4532.841350552715;4535.365048893158;4543.54564543543;4556.8177272908715;4838.395200599925;4996.425411077773;5007.574356179693;5021.347224860745;5093.159628661304;5738.856528586819;5750.482456578928;5751.332113687067;5755.798547050833;5757.453041961007;1550.7748878386174;1568.2237292540265;1577.2192764581605;1579.7283187034318;1584.5404832598417;996.1731762636994;1028.5908305621347;1047.6909311753052;1050.3225419604312;1068.1348108032364;5531.419200983919;5576.804811831566;5589.9099675562875;5619.438056194348;5640.8871822563315;5787.2476578045425;5846.649201047951;5851.765929362778;5857.941420585739;5859.2090067841145;5459.426760190123;5560.610757246954;5564.666120032811;5586.24585680936;5620.538659948361;3064.862081206351;3089.7219250267585;3098.628164620215;3099.7830151889602;3107.430747472557;5962.672053037038;6054.334023257438;6099.329073801879;6143.631382117087;6154.7230374633245;5757.222774925332;5927.614705044159;5937.465628093406;5940.34656187819;5946.226990491208;6014.8195554133445;6107.206063211754;6150.7232174552255;6151.907721384183;6180.47465965398;5799.246098007232;5843.152742852178;5843.912341314969;5855.268091488564;5909.261342332208;5612.298462692163;5751.223584815996;5873.735681894411;5897.144913987534;5897.941020589911;6125.418085281891;6227.353374815594;6229.190205273286;6237.1891654084675;6253.843039037793;4158.251514727378;4297.59172878576;4335.1113021829815;4351.56484351575;4378.190034843564;5869.941300586939;5886.205362276038;5886.293644762656;5947.553933455095;5965.6718880461995;5775.33583637887;5822.621529600732;5829.650220986754;5871.473416911548;5875.472377181878;5690.03693725664;5803.622764520293;5806.912896306474;5810.683224849216;5816.1857339973285;5705.885882282414;5714.799802076148;5747.885042299215;5764.337101233412;5766.769329226784;5574.9163762542785;5798;5805.390434004369;5806.593538452268;5814.186013957965;5624.268845050117;5640.520555752807;5653.943460565341;5669.54643628517;5679.290088738908 22:03:51 INFO - PROCESS | 8893 | |5;yui.html;816.5468165168332;823.5511646152846;844.4595458906301;846.3398549131675;876.7676565710084;14811.185384803832;15081.773773393394;15424.92287538562;18462.169202385892;18741.62516749664;16452.83547164528;16606.75089873653;16621.91689041556;16661.91669041656;16664.250108745106;744.7467860927284;839.5634270179534;861.4831101339178;886.2378354615042;888.2139306713541;882.849915514364;891.4428482198626;891.857302831547;892.8035832116924;892.9107089291084;938.5917126050156;944.6646440513622;945.0313428735533;945.1068740040664;945.2249155692301;938.1369140390823;942.4062840410497;945.7021038372891;945.9195968342699;946.2240962410826;693.7883945396652;693.9653017349162;694.0318256032856;694.2814187316154;694.3577191098213;16246.75629865553;16266;16279.755803662954;16296.022238665719;16316.347346106142;15443;15480.5355839325;15500.689986200212;15530.068195908281;15563.221838908123;13073.477060917647;13679.794803077963;13714.725705485835;13921.512747053805;14176;4172.624463798243;4198.3702444633245;4216.494020717533;4236.385724069993;4349.891252718708;93878;94622;94642;95008;96652.51673741656;12054.216475929037;12168.452419641137;12336.81494777579;12441.129120961446;12454.25274483534;23365.649515257286;23383.181588644315;23441.296761097194;23511.882440587688;23516.29451116469;9183.173514383738;9183.862242066374;9434.575444105032;9469.384490008128;9470.715878523655;5115.616328775357;5187.974060129675;5189.4032186299055;5220.216967454912;5445.292112025411;4127.855525056609;4200.222958752641;4241.427407299975;4241.978790106092;4256.212600668886;764.6215123513786;767.5356409372249;767.535640937236;769.4152444142492;799.3605115907251;740.472013636619;767.6315368623092;803.0965164190286;804.6499772598821;813.8006188483741;10726.624568140078;11183.720406989727;11287.60493382744;11296.152788540729;11623.65129046113;777.2460713108276;782.2138750555769;803.3412601666694;812.3947658994088;851.3189448441223;5204.661696989683;5253.290805741252;5511.9724401378535;5518.255035570147;5604.971975140098;71805.2819471809;71898.28101719021;71977.64011180015;80152.19847801563;98469;103250.48374758229;103259.48370258101;104120.47939760404;104544;108213;84806.57596711977;87118.56440717883;92939.99999999865;93540.4758706792;93571.53214234022;9853.768951952461;10190.1338386238;10214.233932455245;10373.948130259301;10417.42704151279;56437.153442699215;56568;56597.15104273357;56713;58514;49959.75020124876;50028.24957625566;64374.67812660907;75836.62081689556;76665.61667191629;13266.734665306833;13464.326783660581;13504.729905402033;13857.099288546213;13921.373538190603;14311.57065288043;14344.928275358556;14359.92820035893;14601.050931689406;14806;13631.25028123423;13642.795358069836;13771.380287886868;14052.227127508086;14829.406823727362;13005.739885202433;13294.20234785916;13657.385417656427;13778.724425511635;13875.444982200599;13175.077744558172;13568.660783480493;13992.580222593338;14582.343794529059;15535.689286214438;10617.41604211745;11764.529418823151;11881.465334060162;12230.327331996825;12263.570775022832;11773.660749820807;12425.19236249641;12709.17390369623;12745.17156384832;12746.808797868225 22:03:51 INFO - PROCESS | 8893 | __end_tp_report 22:03:51 INFO - PROCESS | 8893 | __start_cc_report 22:03:51 INFO - PROCESS | 8893 | _x_x_mozilla_cycle_collect,1030 22:03:51 INFO - PROCESS | 8893 | __end_cc_report 22:03:51 INFO - PROCESS | 8893 | __startTimestamp1475557431272__endTimestamp 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | ------- Summary: start ------- 22:03:51 INFO - PROCESS | 8893 | Number of tests: 6 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | [#0] dojo.html Cycles:165 Average:5573.34 Median:5800.80 stddev:637.35 (11.0%) stddev-sans-first:610.51 22:03:51 INFO - PROCESS | 8893 | Values: 3158.8 3188.2 3197.2 3216.5 3236.7 5430.3 5528.1 5537.8 5538.2 5543.1 5615.7 5660.6 5671.1 5677.7 5703.4 5111.3 5128.1 5187.1 5189.9 5206.0 5078.7 5196.8 5220.9 5221.7 5227.6 5043.4 5045.6 5062.9 5067.1 5100.1 5058.1 5094.9 5119.4 5174.6 5206.3 5002.4 5061.5 5114.7 5118.4 5131.6 5418.4 5443.4 5459.2 5462.0 5466.5 5916.1 5926.5 5937.3 5985.9 6016.6 5901.0 6037.9 6038.4 6055.4 6056.8 5499.5 5540.7 5542.1 5542.8 5568.9 6081.8 6082.8 6093.8 6103.8 6107.8 5747.6 5774.0 5858.1 5860.0 5861.4 5861.2 5937.3 5937.3 5963.9 5970.6 5885.6 5889.7 5897.3 5898.6 5902.8 5801.1 5908.8 5911.4 5914.9 5937.2 4586.2 4595.1 4595.6 4645.2 4673.2 4081.1 4103.5 4106.9 4144.3 4147.5 5648.9 5796.2 5800.8 5802.7 5808.1 6002.8 6044.3 6045.7 6119.5 6119.7 5795.9 5798.5 5808.3 5811.6 5842.6 4970.8 5146.2 5148.6 5152.5 5156.5 6180.6 6238.7 6239.6 6342.7 6356.0 6017.2 6052.8 6053.3 6066.2 6068.1 6140.5 6287.9 6332.2 6332.3 6335.5 5834.5 5932.5 5939.4 6005.3 6035.2 6064.2 6068.8 6070.2 6076.9 6082.1 5789.3 5959.4 5967.0 5970.8 5972.3 5766.7 5822.0 5824.3 5903.3 5904.5 5737.0 5786.4 5787.0 5790.3 5793.1 5826.2 5896.6 5900.1 5902.3 5914.1 5524.2 5739.8 5772.6 5784.3 5839.1 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | [#1] ext.html Cycles:180 Average:63956.29 Median:21753.51 stddev:128699.75 (591.6%) stddev-sans-first:128972.85 22:03:51 INFO - PROCESS | 8893 | Values: 588.6 639.3 668.4 674.3 678.0 35042.3 38229.6 38411.8 38420.2 38468.0 46716.0 47648.0 47807.3 52788.0 52850.0 9233.7 9243.3 9301.8 9311.8 9312.3 8612.6 9218.6 9380.4 11384.0 11589.1 11455.7 11491.5 11516.6 11522.5 11522.6 11328.3 11379.3 11380.9 11385.5 11387.3 11167.3 11228.4 11230.9 11236.2 11242.7 46090.8 46201.0 46371.5 46390.8 46554.0 3913.1 3937.0 4078.1 4080.5 4450.2 35922.5 36034.8 36604.8 36622.6 36942.6 4516.5 4575.6 4578.3 4601.3 4693.0 361443.0 363990.0 364650.0 365121.0 366000.0 38807.6 66055.0 66258.0 66286.7 66353.0 39842.6 41993.8 41997.0 42024.4 42029.4 17644.0 17895.1 17899.6 17910.7 17911.0 14968.8 15074.2 15077.6 15088.3 15120.8 8749.9 9152.8 9563.8 9574.6 9739.7 3526.5 3581.3 3620.1 3646.3 3653.6 996.5 1027.6 1047.3 1082.6 1086.5 35101.0 37089.0 37347.0 38140.0 39570.0 1147.6 1149.2 1204.5 1225.0 1299.9 18123.6 18571.6 18595.0 18691.8 18744.1 604351.0 613109.0 614023.0 616663.0 618091.0 64623.0 64857.4 64922.7 65047.0 65121.7 1356.6 1396.5 1443.3 1467.4 1502.0 31447.8 36624.6 36805.1 36863.8 37154.8 55130.4 55808.4 56052.4 56069.0 56117.7 437717.0 439821.0 442129.0 443098.0 444082.0 27825.2 29979.3 31186.8 31277.0 32221.2 51232.0 52392.0 52481.5 52599.0 52993.2 43538.4 44476.8 44663.6 45582.8 46518.8 43563.0 44270.0 44655.6 45457.5 45786.5 43816.3 43944.0 44012.0 44437.8 45434.0 37632.6 39065.0 39205.8 40025.8 40308.0 29676.7 30111.8 30238.5 30545.4 30739.5 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | [#2] jquery.html Cycles:180 Average:31122.56 Median:11683.11 stddev:89627.41 (767.2%) stddev-sans-first:89849.18 22:03:51 INFO - PROCESS | 8893 | Values: 415.6 424.4 434.6 438.2 447.1 18949.0 20394.6 20784.8 21244.9 21524.7 20345.0 20412.9 20713.5 20886.8 20892.2 5771.2 6006.6 6010.8 6014.3 6015.7 5809.4 5862.2 6034.5 6066.2 6170.9 5988.6 6149.5 6149.5 6156.7 6166.4 6126.8 6184.5 6198.5 6216.8 6226.3 5880.3 5980.3 6037.5 6041.4 6042.0 15362.0 15448.9 15486.0 15549.9 15591.0 2470.4 2499.0 2658.8 2715.7 2893.4 10695.0 10902.3 10957.5 11117.4 11121.7 1923.8 2035.4 2041.8 2103.9 2121.3 83387.0 85664.6 87467.1 88630.6 89394.0 11663.5 11936.7 12036.7 12043.9 12086.6 17250.7 17261.5 17269.1 17289.1 17305.2 8944.0 8991.9 9035.3 9197.9 9306.2 8727.3 8774.8 8840.5 8985.7 9098.6 5093.1 5199.5 5217.6 5272.0 5309.7 2002.7 2033.6 2057.8 2069.4 2087.8 971.4 1024.4 1048.9 1080.8 1081.2 19054.3 20685.8 20908.4 20910.8 21121.7 1029.8 1048.8 1062.2 1074.7 1128.8 10811.6 11812.6 11866.9 11873.1 11897.2 522613.0 533490.0 543436.0 560554.0 560923.0 91464.5 91941.5 94241.5 95073.0 95608.5 1045.6 1069.8 1080.3 1092.5 1094.0 17654.8 17716.0 17793.6 18457.4 18692.3 43438.8 43847.3 44910.6 45191.8 46197.5 62185.4 62236.7 62755.1 62962.0 63618.7 8854.4 9016.0 9087.8 9346.9 9484.5 11235.5 13005.8 13282.5 13375.3 13381.8 11634.7 11722.5 11730.9 11822.5 11825.8 10693.1 11250.3 11829.8 11889.8 11921.8 7140.1 11767.5 11818.2 12374.8 12898.1 7973.7 8226.1 8918.1 9127.5 9176.9 11739.2 12013.8 12023.8 12079.8 12270.5 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | [#3] mootools.html Cycles:180 Average:6064.12 Median:2620.01 stddev:3504.36 (133.8%) stddev-sans-first:3495.43 22:03:51 INFO - PROCESS | 8893 | Values: 1239.7 1529.3 1541.2 1740.8 1877.9 8072.7 8718.2 9118.8 9149.1 9471.0 9181.4 9239.7 9243.6 9252.5 9254.1 2393.6 2420.7 2423.1 2426.9 2429.2 2374.8 2387.8 2448.4 2450.7 2459.2 2383.6 2385.6 2391.9 2416.0 2416.9 2366.8 2376.3 2381.0 2383.9 2401.7 2356.7 2358.6 2365.7 2365.8 2367.7 8725.5 9157.3 9179.9 9238.8 9250.7 5244.3 5518.5 5519.2 5519.3 5520.8 3630.2 3659.3 3678.0 3688.9 3722.0 1255.9 1261.2 1268.1 1285.2 1291.5 12925.9 12970.4 13003.0 13008.7 13015.7 4283.8 4538.6 4615.9 4716.4 4760.2 9779.1 9813.4 9847.1 9849.2 9851.0 5243.7 5246.1 5252.4 5260.8 5261.3 4527.7 4882.8 4939.7 4987.8 5025.6 3931.5 3999.7 4029.9 4076.2 4099.5 1187.1 1199.9 1201.5 1205.8 1212.9 1050.4 1070.9 1074.6 1077.2 1086.0 6127.5 6156.8 6172.4 6243.9 6482.3 479.8 481.2 492.8 507.6 508.6 3927.5 3950.4 4013.1 4017.2 4235.5 11261.0 11786.2 11794.1 11811.1 11825.3 11716.4 11735.1 11736.8 11747.9 11775.8 8638.2 8678.4 8831.9 8837.9 8862.3 6323.6 6352.4 6372.1 6450.2 6488.8 10555.6 10563.4 10659.2 10711.0 10845.4 10796.7 10898.9 10937.9 11006.8 11059.3 7019.3 7439.9 7688.8 7779.5 7916.3 9216.8 9293.8 9294.2 9295.4 9297.0 8255.9 8284.2 8386.0 8426.7 8484.0 7987.8 8106.4 8206.2 8212.1 8416.7 7363.8 7471.1 7475.6 7523.0 7553.3 5684.7 5697.8 5769.1 5775.6 5873.6 5515.1 5517.5 5656.8 5736.1 5779.4 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | [#4] prototype.html Cycles:180 Average:4899.89 Median:5556.22 stddev:1405.52 (25.3%) stddev-sans-first:1367.32 22:03:51 INFO - PROCESS | 8893 | Values: 348.7 354.0 355.4 360.5 363.9 5516.8 5517.4 5526.4 5545.6 5551.8 5443.0 5472.8 5497.6 5511.2 5515.5 3860.4 4078.8 4341.0 4360.9 4366.5 4347.0 4372.0 4377.4 4386.4 4392.8 4384.8 4393.3 4398.8 4404.9 4414.6 4178.9 4365.8 4389.2 4399.2 4401.6 3892.4 3900.1 3902.8 3905.4 3912.2 5326.6 5329.6 5377.6 5405.3 5418.5 4897.0 4926.9 4976.3 5033.6 5093.3 5658.3 5704.4 5715.6 5724.0 5755.7 4353.2 4361.2 4381.8 4385.6 4389.7 5891.6 5977.9 5994.7 6028.6 6059.9 4345.6 4400.6 4428.8 4459.9 4464.5 4532.8 4532.8 4535.4 4543.5 4556.8 4838.4 4996.4 5007.6 5021.3 5093.2 5738.9 5750.5 5751.3 5755.8 5757.5 1550.8 1568.2 1577.2 1579.7 1584.5 996.2 1028.6 1047.7 1050.3 1068.1 5531.4 5576.8 5589.9 5619.4 5640.9 5787.2 5846.6 5851.8 5857.9 5859.2 5459.4 5560.6 5564.7 5586.2 5620.5 3064.9 3089.7 3098.6 3099.8 3107.4 5962.7 6054.3 6099.3 6143.6 6154.7 5757.2 5927.6 5937.5 5940.3 5946.2 6014.8 6107.2 6150.7 6151.9 6180.5 5799.2 5843.2 5843.9 5855.3 5909.3 5612.3 5751.2 5873.7 5897.1 5897.9 6125.4 6227.4 6229.2 6237.2 6253.8 4158.3 4297.6 4335.1 4351.6 4378.2 5869.9 5886.2 5886.3 5947.6 5965.7 5775.3 5822.6 5829.7 5871.5 5875.5 5690.0 5803.6 5806.9 5810.7 5816.2 5705.9 5714.8 5747.9 5764.3 5766.8 5574.9 5798.0 5805.4 5806.6 5814.2 5624.3 5640.5 5653.9 5669.5 5679.3 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - PROCESS | 8893 | [#5] yui.html Cycles:180 Average:20971.42 Median:4241.70 stddev:28797.15 (678.9%) stddev-sans-first:28838.17 22:03:51 INFO - PROCESS | 8893 | Values: 816.5 823.6 844.5 846.3 876.8 14811.2 15081.8 15424.9 18462.2 18741.6 16452.8 16606.8 16621.9 16661.9 16664.3 744.7 839.6 861.5 886.2 888.2 882.8 891.4 891.9 892.8 892.9 938.6 944.7 945.0 945.1 945.2 938.1 942.4 945.7 945.9 946.2 693.8 694.0 694.0 694.3 694.4 16246.8 16266.0 16279.8 16296.0 16316.3 15443.0 15480.5 15500.7 15530.1 15563.2 13073.5 13679.8 13714.7 13921.5 14176.0 4172.6 4198.4 4216.5 4236.4 4349.9 93878.0 94622.0 94642.0 95008.0 96652.5 12054.2 12168.5 12336.8 12441.1 12454.3 23365.6 23383.2 23441.3 23511.9 23516.3 9183.2 9183.9 9434.6 9469.4 9470.7 5115.6 5188.0 5189.4 5220.2 5445.3 4127.9 4200.2 4241.4 4242.0 4256.2 764.6 767.5 767.5 769.4 799.4 740.5 767.6 803.1 804.6 813.8 10726.6 11183.7 11287.6 11296.2 11623.7 777.2 782.2 803.3 812.4 851.3 5204.7 5253.3 5512.0 5518.3 5605.0 71805.3 71898.3 71977.6 80152.2 98469.0 103250.5 103259.5 104120.5 104544.0 108213.0 84806.6 87118.6 92940.0 93540.5 93571.5 9853.8 10190.1 10214.2 10373.9 10417.4 56437.2 56568.0 56597.2 56713.0 58514.0 49959.8 50028.2 64374.7 75836.6 76665.6 13266.7 13464.3 13504.7 13857.1 13921.4 14311.6 14344.9 14359.9 14601.1 14806.0 13631.3 13642.8 13771.4 14052.2 14829.4 13005.7 13294.2 13657.4 13778.7 13875.4 13175.1 13568.7 13992.6 14582.3 15535.7 10617.4 11764.5 11881.5 12230.3 12263.6 11773.7 12425.2 12709.2 12745.2 12746.8 22:03:51 INFO - PROCESS | 8893 | -------- Summary: end -------- 22:03:51 INFO - PROCESS | 8893 | 22:03:51 INFO - TEST-INFO | 8893: exit 0 22:03:52 INFO - TEST-OK | dromaeo_css | took 1111984ms 22:03:52 INFO - TEST-START | kraken 22:03:52 INFO - operating with platform_type : linux_ 22:03:52 INFO - Initialising browser for kraken test... 22:03:52 INFO - TEST-INFO | started process 9083 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgYWh4d/profile http://localhost:43219/getInfo.html) 22:03:55 INFO - PROCESS | 9083 | __metrics Screen width/height:1600/1200 22:03:55 INFO - PROCESS | 9083 | colorDepth:24 22:03:55 INFO - PROCESS | 9083 | Browser inner width/height: 1024/697 22:03:55 INFO - PROCESS | 9083 | __metrics 22:03:56 INFO - PROCESS | 9083 | 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" 22:03:56 INFO - PROCESS | 9083 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:03:56 INFO - PROCESS | 9083 | console.error: PushService: 22:03:56 INFO - PROCESS | 9083 | stateChangeProcessEnqueue: Error transitioning state 22:03:56 INFO - PROCESS | 9083 | UnknownError 22:03:57 INFO - TEST-INFO | 9083: exit 0 22:03:57 INFO - Browser initialized. 22:03:57 INFO - Running cycle 1/1 for kraken test... 22:03:57 INFO - TEST-INFO | started process 9172 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgYWh4d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:04:09 INFO - PROCESS | 9172 | RSS: Main: 143560704 22:04:09 INFO - PROCESS | 9172 | 22:04:11 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43219/tests/kraken/driver.html?testName=audio-beat-detection) 22:04:12 INFO - PROCESS | 9172 | RSS: Main: 199049216 22:04:12 INFO - PROCESS | 9172 | 22:04:15 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43219/tests/kraken/driver.html?testName=audio-dft) 22:04:15 INFO - PROCESS | 9172 | RSS: Main: 183017472 22:04:15 INFO - PROCESS | 9172 | 22:04:18 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43219/tests/kraken/driver.html?testName=audio-fft) 22:04:19 INFO - PROCESS | 9172 | RSS: Main: 274718720 22:04:19 INFO - PROCESS | 9172 | 22:04:21 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43219/tests/kraken/driver.html?testName=audio-oscillator) 22:04:21 INFO - PROCESS | 9172 | RSS: Main: 179863552 22:04:21 INFO - PROCESS | 9172 | 22:04:23 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:04:24 INFO - PROCESS | 9172 | RSS: Main: 178491392 22:04:24 INFO - PROCESS | 9172 | 22:04:27 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43219/tests/kraken/driver.html?testName=imaging-darkroom) 22:04:27 INFO - PROCESS | 9172 | RSS: Main: 214515712 22:04:27 INFO - PROCESS | 9172 | 22:04:31 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43219/tests/kraken/driver.html?testName=imaging-desaturate) 22:04:31 INFO - PROCESS | 9172 | RSS: Main: 213987328 22:04:31 INFO - PROCESS | 9172 | 22:04:34 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43219/tests/kraken/driver.html?testName=json-parse-financial) 22:04:35 INFO - PROCESS | 9172 | RSS: Main: 213872640 22:04:35 INFO - PROCESS | 9172 | 22:04:36 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:04:37 INFO - PROCESS | 9172 | RSS: Main: 175734784 22:04:37 INFO - PROCESS | 9172 | 22:04:39 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:04:39 INFO - PROCESS | 9172 | RSS: Main: 182853632 22:04:39 INFO - PROCESS | 9172 | 22:04:41 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:04:42 INFO - PROCESS | 9172 | RSS: Main: 184782848 22:04:42 INFO - PROCESS | 9172 | 22:04:44 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:04:45 INFO - PROCESS | 9172 | RSS: Main: 185294848 22:04:45 INFO - PROCESS | 9172 | 22:04:47 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:04:48 INFO - PROCESS | 9172 | RSS: Main: 182624256 22:04:48 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | Cycle 1(1): loaded http://localhost:43219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43219/tests/kraken/driver.html?testName=ai-astar) 22:04:50 INFO - PROCESS | 9172 | RSS: Main: 233963520 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | __start_tp_report 22:04:50 INFO - PROCESS | 9172 | _x_x_mozilla_page_load 22:04:50 INFO - PROCESS | 9172 | _x_x_mozilla_page_load_details 22:04:50 INFO - PROCESS | 9172 | |i|pagename|runs| 22:04:50 INFO - PROCESS | 9172 | |0;ai-astar;110;109;120;109;109;109;108;111;110;110 22:04:50 INFO - PROCESS | 9172 | |1;audio-beat-detection;144;115;142;176;120;148;141;116;172;117 22:04:50 INFO - PROCESS | 9172 | |2;audio-dft;160;160;163;161;161;161;160;161;160;160 22:04:50 INFO - PROCESS | 9172 | |3;audio-fft;74;75;76;171;76;74;75;75;175;75 22:04:50 INFO - PROCESS | 9172 | |4;audio-oscillator;84;84;148;84;83;84;137;83;83;147 22:04:50 INFO - PROCESS | 9172 | |5;imaging-gaussian-blur;117;117;118;117;117;116;116;117;117;116 22:04:50 INFO - PROCESS | 9172 | |6;imaging-darkroom;146;146;145;145;146;147;146;147;146;145 22:04:50 INFO - PROCESS | 9172 | |7;imaging-desaturate;94;95;96;96;96;96;95;95;96;96 22:04:50 INFO - PROCESS | 9172 | |8;json-parse-financial;51;51;54;49;52;50;51;50;50;50 22:04:50 INFO - PROCESS | 9172 | |9;json-stringify-tinderbox;59;59;60;59;59;59;59;59;58;59 22:04:50 INFO - PROCESS | 9172 | |10;stanford-crypto-aes;73;74;72;73;77;73;78;73;73;72 22:04:50 INFO - PROCESS | 9172 | |11;stanford-crypto-ccm;128;120;120;124;117;121;117;114;157;125 22:04:50 INFO - PROCESS | 9172 | |12;stanford-crypto-pbkdf2;152;150;151;152;155;148;151;149;154;153 22:04:50 INFO - PROCESS | 9172 | |13;stanford-crypto-sha256-iterative;63;68;60;61;60;63;61;60;60;62 22:04:50 INFO - PROCESS | 9172 | __end_tp_report 22:04:50 INFO - PROCESS | 9172 | __start_cc_report 22:04:50 INFO - PROCESS | 9172 | _x_x_mozilla_cycle_collect,1689 22:04:50 INFO - PROCESS | 9172 | __end_cc_report 22:04:50 INFO - PROCESS | 9172 | __startTimestamp1475557490465__endTimestamp 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | ------- Summary: start ------- 22:04:50 INFO - PROCESS | 9172 | Number of tests: 14 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#0] ai-astar Cycles:10 Average:110.50 Median:110.00 stddev:3.44 (3.1%) stddev-sans-first:3.64 22:04:50 INFO - PROCESS | 9172 | Values: 110.0 109.0 120.0 109.0 109.0 109.0 108.0 111.0 110.0 110.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:143.00 stddev:22.43 (15.7%) stddev-sans-first:23.72 22:04:50 INFO - PROCESS | 9172 | Values: 144.0 115.0 142.0 176.0 120.0 148.0 141.0 116.0 172.0 117.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 22:04:50 INFO - PROCESS | 9172 | Values: 160.0 160.0 163.0 161.0 161.0 161.0 160.0 161.0 160.0 160.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:41.34 (55.1%) stddev-sans-first:43.17 22:04:50 INFO - PROCESS | 9172 | Values: 74.0 75.0 76.0 171.0 76.0 74.0 75.0 75.0 175.0 75.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:83.50 stddev:29.33 (35.1%) stddev-sans-first:30.41 22:04:50 INFO - PROCESS | 9172 | Values: 84.0 84.0 148.0 84.0 83.0 84.0 137.0 83.0 83.0 147.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 22:04:50 INFO - PROCESS | 9172 | Values: 117.0 117.0 118.0 117.0 117.0 116.0 116.0 117.0 117.0 116.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 22:04:50 INFO - PROCESS | 9172 | Values: 146.0 146.0 145.0 145.0 146.0 147.0 146.0 147.0 146.0 145.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.71 (0.7%) stddev-sans-first:0.50 22:04:50 INFO - PROCESS | 9172 | Values: 94.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.40 (2.7%) stddev-sans-first:1.48 22:04:50 INFO - PROCESS | 9172 | Values: 51.0 51.0 54.0 49.0 52.0 50.0 51.0 50.0 50.0 50.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 22:04:50 INFO - PROCESS | 9172 | Values: 59.0 59.0 60.0 59.0 59.0 59.0 59.0 59.0 58.0 59.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.00 stddev:2.04 (2.8%) stddev-sans-first:2.15 22:04:50 INFO - PROCESS | 9172 | Values: 73.0 74.0 72.0 73.0 77.0 73.0 78.0 73.0 73.0 72.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:122.50 stddev:12.22 (10.0%) stddev-sans-first:12.89 22:04:50 INFO - PROCESS | 9172 | Values: 128.0 120.0 120.0 124.0 117.0 121.0 117.0 114.0 157.0 125.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.50 Median:152.00 stddev:2.17 (1.4%) stddev-sans-first:2.30 22:04:50 INFO - PROCESS | 9172 | Values: 152.0 150.0 151.0 152.0 155.0 148.0 151.0 149.0 154.0 153.0 22:04:50 INFO - PROCESS | 9172 | 22:04:50 INFO - PROCESS | 9172 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:2.49 (4.0%) stddev-sans-first:2.60 22:04:50 INFO - PROCESS | 9172 | Values: 63.0 68.0 60.0 61.0 60.0 63.0 61.0 60.0 60.0 62.0 22:04:50 INFO - PROCESS | 9172 | -------- Summary: end -------- 22:04:50 INFO - PROCESS | 9172 | 22:04:51 INFO - TEST-INFO | 9172: exit 0 22:04:51 INFO - TEST-OK | kraken | took 59427ms 22:04:51 INFO - SUITE-END | took 1171s 22:04:51 INFO - Completed test suite (00:19:31) 22:04:51 INFO - javascript benchmark 22:04:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3158.8262645554514, 3188.171075520365, 3197.2326641606014, 3216.533602627619, 3236.676332366762, 5430.294061771974, 5528.087865502197, 5537.75080121395, 5538.169274608806, 5543.113101903697, 5615.7472913718875, 5660.575456840733, 5671.120976248678, 5677.744501497432, 5703.40114288, 5111.284420181178, 5128.1282182029145, 5187.144121219994, 5189.896202075956, 5206.036883176605, 5078.669886457387, 5196.844094677166, 5220.869478263055, 5221.686698798066, 5227.581793456533, 5043.420006699222, 5045.621578381618, 5062.924056139161, 5067.0879241736475, 5100.107481190796, 5058.140116180258, 5094.872628184306, 5119.436861945183, 5174.586033117342, 5206.271122042917, 5002.449730529639, 5061.468545802712, 5114.667546609496, 5118.436971933084, 5131.563817075553, 5418.431064738185, 5443.428440013781, 5459.153831156177, 5462.044142275086, 5466.508014278734, 5916.142159386908, 5926.525877929756, 5937.287525496925, 5985.91021134679, 6016.639001659914, 5901.0, 6037.879242415171, 6038.396160383971, 6055.394460553954, 6056.788012419544, 5499.450054994509, 5540.667559946335, 5542.057850165482, 5542.750576224039, 5568.916466253009, 6081.756729730761, 6082.817515474543, 6093.75624974996, 6103.786367477206, 6107.755689772448, 5747.568932330092, 5773.971130144407, 5858.06270996647, 5860.0, 5861.3845546217035, 5861.20873682056, 5937.2578427696535, 5937.317208520989, 5963.850903727354, 5970.582059255898, 5885.5585831062845, 5889.734961926723, 5897.321807992136, 5898.557608179403, 5902.763889444374, 5801.129830525455, 5908.822735317947, 5911.379305172981, 5914.881702366015, 5937.198478205473, 4586.174488592019, 4595.058013107305, 4595.586397224199, 4645.233536466513, 4673.182193116191, 4081.0817566047403, 4103.528094269138, 4106.856260030884, 4144.254034273859, 4147.460830092129, 5648.943510564924, 5796.159556864315, 5800.796972105956, 5802.709864506758, 5808.128780682931, 6002.759889604367, 6044.274687037584, 6045.667488288186, 6119.472088496418, 6119.746288422706, 5795.8551036224435, 5798.478136967693, 5808.30300363959, 5811.622244554091, 5842.649441033466, 4970.776315065831, 5146.150885103916, 5148.588112950955, 5152.536271735562, 5156.484351564888, 6180.5673602848265, 6238.719257633417, 6239.594426362272, 6342.714577844008, 6355.968220158962, 6017.157597936205, 6052.788152414644, 6053.303870055, 6066.241719785027, 6068.120122582289, 6140.534923917628, 6287.874242515032, 6332.240131184288, 6332.3034466207755, 6335.461485773761, 5834.533237341087, 5932.466078052996, 5939.406059394199, 6005.309389420097, 6035.215421995113, 6064.241969753781, 6068.848278793065, 6070.2412198475195, 6076.90884636722, 6082.118092876596, 5789.305283365855, 5959.374265702038, 5967.0, 5970.791022314198, 5972.25346831646, 5766.682832444256, 5821.970890145692, 5824.330202026821, 5903.291605007426, 5904.498117660047, 5736.971315143398, 5786.392428794916, 5787.0, 5790.305163380422, 5793.131030345482, 5826.213461182686, 5896.557758168069, 5900.1149827526215, 5902.2622172228475, 5914.142449344907, 5524.226608274925, 5739.770409183753, 5772.56705747079, 5784.334801497882, 5839.065749480063], "value": 5529.747897617944, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.5968111843388, 639.2999665366424, 668.3884245914986, 674.302097329264, 678.0473434824074, 35042.2991540169, 38229.61770382295, 38411.80794096032, 38420.23159536815, 38468.038299042506, 46716.0, 47648.0, 47807.282890756665, 52788.0, 52850.0, 9233.676821311255, 9243.260539156885, 9301.767455813591, 9311.76720581984, 9312.34813563051, 8612.612432440555, 9218.585163667618, 9380.390274632162, 11384.0, 11589.130815188852, 11455.713607159805, 11491.482883270231, 11516.560429946257, 11522.539098436095, 11522.596709115183, 11328.320300781937, 11379.317240965527, 11380.943095284554, 11385.487653055632, 11387.25982811123, 11167.274127181707, 11228.382438965935, 11230.943845280803, 11236.21346505745, 11242.66272011841, 46090.769546152056, 46201.0, 46371.53628463706, 46390.768046159894, 46554.0, 3913.0804260998643, 3937.0118040478096, 4078.1028173801715, 4080.510338759338, 4450.221211288044, 35922.461163082575, 36034.819825900966, 36604.81697591495, 36622.633773662455, 36942.63057369419, 4516.503184649686, 4575.633949284049, 4578.267477203632, 4601.309803529464, 4692.953070469286, 361443.0, 363990.0, 364650.0, 365121.0, 366000.0, 38807.61192388096, 66055.0, 66258.0, 66286.67996620192, 66353.0, 39842.60157398389, 41993.79003105026, 41997.0, 42024.36963445551, 42029.36955945663, 17644.0, 17895.10524473771, 17899.552511187063, 17910.731339029924, 17911.0, 14968.775468367765, 15074.170920599254, 15077.623059423602, 15088.321025553876, 15120.848791511944, 8749.912500875038, 9152.816943661222, 9563.808723825485, 9574.617015319449, 9739.659111931049, 3526.501236974276, 3581.28374325136, 3620.0768803954825, 3646.34365814156, 3653.5798383186275, 996.4569309726216, 1027.635189507729, 1047.2773786087575, 1082.5940272397852, 1086.46220121039, 35101.0, 37089.0, 37347.0, 38140.0, 39570.0, 1147.6270212181075, 1149.1898211760688, 1204.476052916984, 1225.0261042471257, 1299.9285039322929, 18123.637527249382, 18571.628567428845, 18595.0, 18691.81308186928, 18744.062796860104, 604351.0, 613109.0, 614023.0, 616663.0, 618091.0, 64623.0, 64857.35142648513, 64922.67538662277, 65047.0, 65121.67439162869, 1356.559118286541, 1396.4763213794827, 1443.3072125379877, 1467.449706360115, 1501.9699606007819, 31447.84276078605, 36624.63375366212, 36805.079873003386, 36863.8156809225, 37154.8142259287, 55130.44869551253, 55808.44191558195, 56052.439475606356, 56069.0, 56117.71941140268, 437717.0, 439821.0, 442129.0, 443098.0, 444082.0, 27825.16524504268, 29979.250518737204, 31186.844065779525, 31277.0, 32221.194470138435, 51232.0, 52392.0, 52481.47518524766, 52599.0, 52993.20510192273, 43538.43704602813, 44476.777616111714, 44663.55336446594, 45582.77208613935, 46518.767406162755, 43563.0, 44270.0, 44655.55344446514, 45457.54542454533, 45786.54213457823, 43816.34275485934, 43944.0, 44012.0, 44437.77781111074, 45434.0, 37632.623673762915, 39065.0, 39205.80397097996, 40025.79987100046, 40308.0, 29676.703232967393, 30111.849440752656, 30238.54642180325, 30545.389092218476, 30739.538906916863], "value": 20889.752178300863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.5574313356277, 424.4119418060331, 434.5610932957713, 438.20903269598364, 447.10132633406863, 18949.0, 20394.592108157845, 20784.792152078473, 21244.893775531138, 21524.677129843025, 20345.0, 20412.89793551034, 20713.482162945933, 20886.791132088674, 20892.164313427482, 5771.192033115367, 6006.609570377933, 6010.789622363218, 6014.338422773491, 6015.6992150392525, 5809.3900140485275, 5862.179294898696, 6034.5172386209215, 6066.241719785027, 6170.938290617081, 5988.580799344048, 6149.477294429978, 6149.508039356863, 6156.692165391735, 6166.38336166382, 6126.7855625053135, 6184.536159788011, 6198.535109866733, 6216.782412615582, 6226.3462336454695, 5880.26496687914, 5980.342162362137, 6037.45662890342, 6041.426064523863, 6042.0, 15362.0, 15448.922755386264, 15486.0, 15549.922250388789, 15591.0, 2470.3824043989002, 2498.9875050624814, 2658.8404695718127, 2715.660542432196, 2893.4068515954177, 10695.0, 10902.345859248471, 10957.452127393679, 11117.444127793578, 11121.72195695106, 1923.7595300587427, 2035.4097311779567, 2041.7549894012814, 2103.905324260397, 2121.331780489141, 83387.0, 85664.57167714184, 87467.12532874718, 88630.55684721535, 89394.0, 11663.533458661559, 11936.701582460335, 12036.699082522831, 12043.87956120445, 12086.637400877988, 17250.65498690019, 17261.482155535356, 17269.13654317304, 17289.13554322279, 17305.221265043103, 8943.971443284106, 8991.865122023044, 9035.322350823715, 9197.908020919838, 9306.162445379812, 8727.345449091343, 8774.824503509893, 8840.469571825583, 8985.685501007563, 9098.636054557875, 5093.108705976513, 5199.480051994846, 5217.6347655664495, 5271.976096132565, 5309.707966061904, 2002.6982461399916, 2033.6136134134467, 2057.753069631654, 2069.4102180878376, 2087.77034526202, 971.4414211828228, 1024.4058446101244, 1048.9318194317345, 1080.8054550180887, 1081.2431298091367, 19054.333098341493, 20685.793142068687, 20908.37274881756, 20910.790892090885, 21121.68317475239, 1029.7785976015193, 1048.826943554305, 1062.245805478119, 1074.7259448840496, 1128.785530749155, 10811.567537298577, 11812.645620631223, 11866.88133118675, 11873.109516786102, 11897.226680265756, 522613.0, 533490.0, 543436.0, 560554.0, 560923.0, 91464.54267728752, 91941.54029229812, 94241.5287923556, 95073.0, 95608.52195739116, 1045.5869931377063, 1069.7964789611688, 1080.2762149359949, 1092.4919912240846, 1093.9507722152523, 17654.823451765318, 17716.0, 17793.644127117128, 18457.35399261073, 18692.25230990745, 43438.782806085765, 43847.34228986631, 44910.55089449064, 45191.77404112958, 46197.53802461932, 62185.37814621796, 62236.68881655744, 62755.05867411901, 62962.0, 63618.68190659017, 8854.424462410181, 9015.954920225357, 9087.772805679911, 9346.906530934604, 9484.525773711424, 11235.494402751732, 13005.804912926504, 13282.53511127106, 13375.331233438485, 13381.799273011107, 11634.6509604712, 11722.531098756295, 11730.944215020689, 11822.467988940347, 11825.822612660644, 10693.14454843595, 11250.312453132095, 11829.822552661544, 11889.82165267539, 11921.82117268259, 7140.07179066718, 11767.470463829319, 11818.231814932002, 12374.81437778452, 12898.097133200587, 7973.681052757825, 8226.136255693065, 8918.10818918103, 9127.452352858849, 9176.852893388326, 11739.178257521891, 12013.81979270329, 12023.75952480963, 12079.75840483203, 12270.509179633073], "value": 9666.380916871201, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1239.6652903716003, 1529.3347393883657, 1541.2216830500595, 1740.8433241008306, 1877.8779379340342, 8072.71745488908, 8718.21536061755, 9118.817623647523, 9149.085091490848, 9471.0, 9181.449113053204, 9239.722808315744, 9243.6302547898, 9252.491112988782, 9254.12085851843, 2393.5571919194963, 2420.6732091167733, 2423.09134074722, 2426.890789914449, 2429.161939130997, 2374.798142157919, 2387.8209134315, 2448.400141965221, 2450.6936632920883, 2459.213051823417, 2383.594788885885, 2385.558671645751, 2391.8564886106888, 2416.0094361311867, 2416.903323867043, 2366.7988221001237, 2376.251480783548, 2380.9761902381015, 2383.892724827387, 2401.6877805885297, 2356.7289761677457, 2358.634411666177, 2365.6924599802087, 2365.7989070928993, 2367.7158740951195, 8725.520096394695, 9157.267418606496, 9179.862302065474, 9238.769030774218, 9250.676226332045, 5244.292020577212, 5518.475744804237, 5519.199716041152, 5519.337679478488, 5520.806771762969, 3630.1832087780303, 3659.323025240339, 3678.043708635774, 3688.8708895188806, 3722.0, 1255.8869701726796, 1261.2495565138727, 1268.1313300389263, 1285.1710646632869, 1291.5092264939262, 12925.935370323183, 12970.416331265116, 13003.0, 13008.674783130498, 13015.674608134777, 4283.807228674717, 4538.568835960578, 4615.9076818463445, 4716.3868697069165, 4760.190767569452, 9779.070988256104, 9813.362131461432, 9847.113759761656, 9849.162821160138, 9851.0, 5243.68537887728, 5246.0557099721655, 5252.395974462909, 5260.815871444558, 5261.263423120689, 4527.705699129546, 4882.804687812519, 4939.678920870132, 4987.750612469362, 5025.597952163818, 3931.5282166140246, 3999.6600288975765, 4029.879103626896, 4076.184763047402, 4099.478820525355, 1187.145255416099, 1199.8920097191294, 1201.5193922431097, 1205.7588482303574, 1212.9150959432754, 1050.4117694091333, 1070.9196810239107, 1074.6399956014666, 1077.1867240233573, 1086.0497065068066, 6127.509799216052, 6156.846078847975, 6172.382761723792, 6243.937560624427, 6482.286948435668, 479.8296604705349, 481.1531704200632, 492.81026804680454, 507.6015327967528, 508.64140780749483, 3927.489426374553, 3950.367941129463, 4013.0974723475574, 4017.196560687874, 4235.512916014698, 11261.0, 11786.174967752346, 11794.056475482112, 11811.114166437552, 11825.349605771593, 11716.414179291001, 11735.119866010084, 11736.823947640793, 11747.941260293645, 11775.823362649568, 8638.222559969507, 8678.39251252419, 8831.91168088311, 8837.867431988525, 8862.291016718647, 6323.588966717149, 6352.396522330371, 6372.07604897278, 6450.193725784277, 6488.837779055561, 10555.577776888838, 10563.419011954109, 10659.20055995819, 10711.0, 10845.403502807105, 10796.67609971702, 10898.945505272422, 10937.890621093688, 11006.834897476385, 11059.281146725438, 7019.2629773873, 7439.888401673871, 7688.846223075395, 7779.533228006338, 7916.287534121957, 9216.815663686823, 9293.767655808659, 9294.163525282487, 9295.442273463614, 9297.0, 8255.917440825515, 8284.212999765012, 8385.95807020961, 8426.747197584082, 8483.95758021206, 7987.760367188994, 8106.351491880518, 8206.179382061746, 8212.137725538732, 8416.653335466295, 7363.77908662741, 7471.140818805907, 7475.551466912003, 7523.0, 7553.282438168365, 5684.687342196054, 5697.800576979786, 5769.134629805562, 5775.624584402, 5873.647581144974, 5515.117581186991, 5517.475839795213, 5656.830295091154, 5736.139579063174, 5779.36426993022], "value": 4758.1861514876955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.7070860477199, 353.9539859818224, 355.3922792025636, 360.54751287134644, 363.8744633101579, 5516.7517461714215, 5517.420670829561, 5526.41972592878, 5545.639533430324, 5551.833444996657, 5442.958890416094, 5472.808451704191, 5497.642653227537, 5511.173324001402, 5515.503604675579, 3860.3823388257883, 4078.7770664987506, 4340.97829510852, 4360.934585981213, 4366.497852746943, 4346.9999999999845, 4372.0, 4377.430933978579, 4386.429764130675, 4392.824287028515, 4384.8026838792175, 4393.340998850182, 4398.780060996954, 4404.933925991113, 4414.624756895668, 4178.8537401191115, 4365.825366985317, 4389.188000219953, 4399.186150562125, 4401.60385565299, 3892.4355968384425, 3900.083480382108, 3902.804859757029, 3905.4141878718137, 3912.2371137628174, 5326.627136100475, 5329.573634109262, 5377.596680248997, 5405.324334458192, 5418.509909774804, 4896.975515122437, 4926.871436637725, 4976.278439626234, 5033.647644664875, 5093.312402825608, 5658.292713410824, 5704.372519022904, 5715.6284841485585, 5723.971380143073, 5755.654660720328, 4353.238183317907, 4361.193179261847, 4381.75900325482, 4385.574688226339, 4389.714668546534, 5891.587588868781, 5977.894089593439, 5994.700264986777, 6028.638281703112, 6059.878802423927, 4345.587169218919, 4400.559944005574, 4428.778561071934, 4459.910801783946, 4464.508904020555, 4532.818687252539, 4532.841350552715, 4535.365048893158, 4543.54564543543, 4556.8177272908715, 4838.395200599925, 4996.425411077773, 5007.574356179693, 5021.347224860745, 5093.159628661304, 5738.856528586819, 5750.482456578928, 5751.332113687067, 5755.798547050833, 5757.453041961007, 1550.7748878386174, 1568.2237292540265, 1577.2192764581605, 1579.7283187034318, 1584.5404832598417, 996.1731762636994, 1028.5908305621347, 1047.6909311753052, 1050.3225419604312, 1068.1348108032364, 5531.419200983919, 5576.804811831566, 5589.9099675562875, 5619.438056194348, 5640.8871822563315, 5787.2476578045425, 5846.649201047951, 5851.765929362778, 5857.941420585739, 5859.2090067841145, 5459.426760190123, 5560.610757246954, 5564.666120032811, 5586.24585680936, 5620.538659948361, 3064.862081206351, 3089.7219250267585, 3098.628164620215, 3099.7830151889602, 3107.430747472557, 5962.672053037038, 6054.334023257438, 6099.329073801879, 6143.631382117087, 6154.7230374633245, 5757.222774925332, 5927.614705044159, 5937.465628093406, 5940.34656187819, 5946.226990491208, 6014.8195554133445, 6107.206063211754, 6150.7232174552255, 6151.907721384183, 6180.47465965398, 5799.246098007232, 5843.152742852178, 5843.912341314969, 5855.268091488564, 5909.261342332208, 5612.298462692163, 5751.223584815996, 5873.735681894411, 5897.144913987534, 5897.941020589911, 6125.418085281891, 6227.353374815594, 6229.190205273286, 6237.1891654084675, 6253.843039037793, 4158.251514727378, 4297.59172878576, 4335.1113021829815, 4351.56484351575, 4378.190034843564, 5869.941300586939, 5886.205362276038, 5886.293644762656, 5947.553933455095, 5965.6718880461995, 5775.33583637887, 5822.621529600732, 5829.650220986754, 5871.473416911548, 5875.472377181878, 5690.03693725664, 5803.622764520293, 5806.912896306474, 5810.683224849216, 5816.1857339973285, 5705.885882282414, 5714.799802076148, 5747.885042299215, 5764.337101233412, 5766.769329226784, 5574.9163762542785, 5798.0, 5805.390434004369, 5806.593538452268, 5814.186013957965, 5624.268845050117, 5640.520555752807, 5653.943460565341, 5669.54643628517, 5679.290088738908], "value": 4458.311521746938, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.5468165168332, 823.5511646152846, 844.4595458906301, 846.3398549131675, 876.7676565710084, 14811.185384803832, 15081.773773393394, 15424.92287538562, 18462.169202385892, 18741.62516749664, 16452.83547164528, 16606.75089873653, 16621.91689041556, 16661.91669041656, 16664.250108745106, 744.7467860927284, 839.5634270179534, 861.4831101339178, 886.2378354615042, 888.2139306713541, 882.849915514364, 891.4428482198626, 891.857302831547, 892.8035832116924, 892.9107089291084, 938.5917126050156, 944.6646440513622, 945.0313428735533, 945.1068740040664, 945.2249155692301, 938.1369140390823, 942.4062840410497, 945.7021038372891, 945.9195968342699, 946.2240962410826, 693.7883945396652, 693.9653017349162, 694.0318256032856, 694.2814187316154, 694.3577191098213, 16246.75629865553, 16266.0, 16279.755803662954, 16296.022238665719, 16316.347346106142, 15443.0, 15480.5355839325, 15500.689986200212, 15530.068195908281, 15563.221838908123, 13073.477060917647, 13679.794803077963, 13714.725705485835, 13921.512747053805, 14176.0, 4172.624463798243, 4198.3702444633245, 4216.494020717533, 4236.385724069993, 4349.891252718708, 93878.0, 94622.0, 94642.0, 95008.0, 96652.51673741656, 12054.216475929037, 12168.452419641137, 12336.81494777579, 12441.129120961446, 12454.25274483534, 23365.649515257286, 23383.181588644315, 23441.296761097194, 23511.882440587688, 23516.29451116469, 9183.173514383738, 9183.862242066374, 9434.575444105032, 9469.384490008128, 9470.715878523655, 5115.616328775357, 5187.974060129675, 5189.4032186299055, 5220.216967454912, 5445.292112025411, 4127.855525056609, 4200.222958752641, 4241.427407299975, 4241.978790106092, 4256.212600668886, 764.6215123513786, 767.5356409372249, 767.535640937236, 769.4152444142492, 799.3605115907251, 740.472013636619, 767.6315368623092, 803.0965164190286, 804.6499772598821, 813.8006188483741, 10726.624568140078, 11183.720406989727, 11287.60493382744, 11296.152788540729, 11623.65129046113, 777.2460713108276, 782.2138750555769, 803.3412601666694, 812.3947658994088, 851.3189448441223, 5204.661696989683, 5253.290805741252, 5511.9724401378535, 5518.255035570147, 5604.971975140098, 71805.2819471809, 71898.28101719021, 71977.64011180015, 80152.19847801563, 98469.0, 103250.48374758229, 103259.48370258101, 104120.47939760404, 104544.0, 108213.0, 84806.57596711977, 87118.56440717883, 92939.99999999865, 93540.4758706792, 93571.53214234022, 9853.768951952461, 10190.1338386238, 10214.233932455245, 10373.948130259301, 10417.42704151279, 56437.153442699215, 56568.0, 56597.15104273357, 56713.0, 58514.0, 49959.75020124876, 50028.24957625566, 64374.67812660907, 75836.62081689556, 76665.61667191629, 13266.734665306833, 13464.326783660581, 13504.729905402033, 13857.099288546213, 13921.373538190603, 14311.57065288043, 14344.928275358556, 14359.92820035893, 14601.050931689406, 14806.0, 13631.25028123423, 13642.795358069836, 13771.380287886868, 14052.227127508086, 14829.406823727362, 13005.739885202433, 13294.20234785916, 13657.385417656427, 13778.724425511635, 13875.444982200599, 13175.077744558172, 13568.660783480493, 13992.580222593338, 14582.343794529059, 15535.689286214438, 10617.41604211745, 11764.529418823151, 11881.465334060162, 12230.327331996825, 12263.570775022832, 11773.660749820807, 12425.19236249641, 12709.17390369623, 12745.17156384832, 12746.808797868225], "value": 8124.735502437298, "unit": "score"}], "name": "dromaeo_css", "value": 7598.499538424991, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 120.0, 109.0, 109.0, 109.0, 108.0, 111.0, 110.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 142.0, 176.0, 120.0, 148.0, 141.0, 116.0, 172.0, 117.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 163.0, 161.0, 161.0, 161.0, 160.0, 161.0, 160.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 171.0, 76.0, 74.0, 75.0, 75.0, 175.0, 75.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 148.0, 84.0, 83.0, 84.0, 137.0, 83.0, 83.0, 147.0], "value": 101.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 147.0, 146.0, 147.0, 146.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 54.0, 49.0, 52.0, 50.0, 51.0, 50.0, 50.0, 50.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0, 59.0, 58.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 73.0, 77.0, 73.0, 78.0, 73.0, 73.0, 72.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 120.0, 124.0, 117.0, 121.0, 117.0, 114.0, 157.0, 125.0], "value": 124.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 151.0, 152.0, 155.0, 148.0, 151.0, 149.0, 154.0, 153.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 60.0, 61.0, 60.0, 63.0, 61.0, 60.0, 60.0, 62.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1485.9999999999998, "alertThreshold": 2.0}]} 22:04:51 INFO - Return code: 0 22:04:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:04:51 INFO - # TBPL SUCCESS # 22:04:51 INFO - Running post-action listener: _resource_record_post_action 22:04:51 INFO - [mozharness: 2016-10-04 05:04:51.784207Z] Finished run-tests step (success) 22:04:51 INFO - Running post-run listener: _resource_record_post_run 22:04:51 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290680832; Read time: 16; Write time: 190248 22:04:51 INFO - TinderboxPrint: CPU usage
12.9% 22:04:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:04:51 INFO - TinderboxPrint: I/O write bytes / time
290,680,832 / 190,248 22:04:51 INFO - TinderboxPrint: CPU idle
8,247.2 (87.1%) 22:04:51 INFO - TinderboxPrint: CPU user
1,178.5 (12.4%) 22:04:51 INFO - TinderboxPrint: Swap in / out
0 / 0 22:04:51 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71323648; Read time: 0; Write time: 39936 22:04:51 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 219357184; Read time: 0; Write time: 150312 22:04:52 INFO - Running post-run listener: _upload_blobber_files 22:04:52 INFO - Blob upload gear active. 22:04:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:04:52 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 22:04:52 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'] 22:04:52 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 22:04:52 INFO - (blobuploader) - INFO - Open directory for files ... 22:04:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:04:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:04:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:04:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:04:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:04:57 INFO - (blobuploader) - INFO - Done attempting. 22:04:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:04:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:04:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:04:58 INFO - (blobuploader) - INFO - Done attempting. 22:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:04:58 INFO - (blobuploader) - INFO - Done attempting. 22:04:58 INFO - (blobuploader) - INFO - Iteration through files over. 22:04:58 INFO - Return code: 0 22:04:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:04:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:04:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a992b7ef73a49779c66de0fcdefc5a8bc3048643efcd260ac45ce138acf1593c0a281166b24809708f362f396459e6c8f2b82e02916c13f9f6b67dbda0c57a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d57ec0534b6f699fd60c0273fa6b81853eef1f24d0f68a4d3a0f8e52ad91ced345a42b9cd35e4a504be08aeeda83d75846ee70d53d4b5355b1f99ee5067da97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81912b694d30eb0ab3228389729df1b7d96cd771d36d06cd5a2abfd330d125ff5feff0ade61aa0375e2a39bb53c3a8d7ea1749508132ef6fddce2c072e288312"} 22:04:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:04:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:04:58 INFO - Contents: 22:04:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a992b7ef73a49779c66de0fcdefc5a8bc3048643efcd260ac45ce138acf1593c0a281166b24809708f362f396459e6c8f2b82e02916c13f9f6b67dbda0c57a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d57ec0534b6f699fd60c0273fa6b81853eef1f24d0f68a4d3a0f8e52ad91ced345a42b9cd35e4a504be08aeeda83d75846ee70d53d4b5355b1f99ee5067da97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81912b694d30eb0ab3228389729df1b7d96cd771d36d06cd5a2abfd330d125ff5feff0ade61aa0375e2a39bb53c3a8d7ea1749508132ef6fddce2c072e288312"} 22:04:58 INFO - Running post-run listener: copy_logs_to_upload_dir 22:04:58 INFO - Copying logs to upload dir... 22:04:58 INFO - mkdir: /builds/slave/test/build/upload/logs 22:04:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.576280 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-03 22:04:58.796806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 22:04:58.801753) ========= 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=20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827 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/a992b7ef73a49779c66de0fcdefc5a8bc3048643efcd260ac45ce138acf1593c0a281166b24809708f362f396459e6c8f2b82e02916c13f9f6b67dbda0c57a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d57ec0534b6f699fd60c0273fa6b81853eef1f24d0f68a4d3a0f8e52ad91ced345a42b9cd35e4a504be08aeeda83d75846ee70d53d4b5355b1f99ee5067da97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81912b694d30eb0ab3228389729df1b7d96cd771d36d06cd5a2abfd330d125ff5feff0ade61aa0375e2a39bb53c3a8d7ea1749508132ef6fddce2c072e288312"} build_url:https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011393 build_url: 'https://queue.taskcluster.net/v1/task/chbHm9sHSUqB1RecC9rMsw/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/a992b7ef73a49779c66de0fcdefc5a8bc3048643efcd260ac45ce138acf1593c0a281166b24809708f362f396459e6c8f2b82e02916c13f9f6b67dbda0c57a49", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d57ec0534b6f699fd60c0273fa6b81853eef1f24d0f68a4d3a0f8e52ad91ced345a42b9cd35e4a504be08aeeda83d75846ee70d53d4b5355b1f99ee5067da97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81912b694d30eb0ab3228389729df1b7d96cd771d36d06cd5a2abfd330d125ff5feff0ade61aa0375e2a39bb53c3a8d7ea1749508132ef6fddce2c072e288312"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 22:04:58.835215) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:04:58.835656) ========= 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=20e666a3d4cfbb71505a330e000001b4-1475555822.460860-1802271827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:04:58.916497) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 22:04:58.916854) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 22:04:58.920223) ========= ========= Total master_lag: 0.20 =========