builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-062 starttime: 1477543617.45 results: success (0) buildid: 20161026185434 builduid: 7dffd3c580634f41a327d661d6ea4bb0 revision: 16e7406cee3df74471a05e6fe02f21f654c57bef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.452397) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.452940) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.453266) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.468292) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.468621) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-26 21:46:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 101M=0s 2016-10-26 21:46:57 (101 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094153 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.577637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.578007) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017994 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.627843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:57.628251) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 16e7406cee3df74471a05e6fe02f21f654c57bef --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 16e7406cee3df74471a05e6fe02f21f654c57bef --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=a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-26 21:46:57,965 truncating revision to first 12 chars 2016-10-26 21:46:57,965 Setting DEBUG logging. 2016-10-26 21:46:57,966 attempt 1/10 2016-10-26 21:46:57,966 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/16e7406cee3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 21:46:58,267 unpacking tar archive at: mozilla-beta-16e7406cee3d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.615787 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:58.259188) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:58.259593) ========= script_repo_revision: 16e7406cee3df74471a05e6fe02f21f654c57bef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:58.260057) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:58.260375) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 21:46:58.270036) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 51 secs) (at 2016-10-26 21:46:58.270354) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:46:58 INFO - MultiFileLogger online at 20161026 21:46:58 in /builds/slave/test 21:46:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:46:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:46:58 INFO - {'append_to_log': False, 21:46:58 INFO - 'base_work_dir': '/builds/slave/test', 21:46:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 21:46:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:46:58 INFO - 'branch': 'Mozilla-Beta', 21:46:58 INFO - 'buildbot_json_path': 'buildprops.json', 21:46:58 INFO - 'config_files': ('talos/linux_config.py',), 21:46:58 INFO - 'default_actions': ('clobber', 21:46:58 INFO - 'read-buildbot-config', 21:46:58 INFO - 'download-and-extract', 21:46:58 INFO - 'populate-webroot', 21:46:58 INFO - 'create-virtualenv', 21:46:58 INFO - 'install', 21:46:58 INFO - 'run-tests'), 21:46:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:46:58 INFO - 'download_minidump_stackwalk': True, 21:46:58 INFO - 'download_symbols': 'ondemand', 21:46:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:46:58 INFO - 'tooltool.py': '/tools/tooltool.py', 21:46:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:46:58 INFO - '/tools/misc-python/virtualenv.py')}, 21:46:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:46:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:46:58 INFO - 'installer_path': 'installer.exe', 21:46:58 INFO - 'log_level': 'info', 21:46:58 INFO - 'log_name': 'talos', 21:46:58 INFO - 'log_to_console': True, 21:46:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:46:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:46:58 INFO - 'opt_config_files': (), 21:46:58 INFO - 'pip_index': False, 21:46:58 INFO - 'sps_profile': False, 21:46:58 INFO - 'sps_profile_interval': 0, 21:46:58 INFO - 'suite': 'dromaeojs-e10s', 21:46:58 INFO - 'system_bits': '32', 21:46:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:46:58 INFO - 'title': 'talos-linux64-ix-062', 21:46:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:46:58 INFO - 'use_talos_json': True, 21:46:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:46:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:46:58 INFO - 'work_dir': 'build'} 21:46:58 INFO - [mozharness: 2016-10-27 04:46:58.701528Z] Running clobber step. 21:46:58 INFO - Running pre-action listener: _resource_record_pre_action 21:46:58 INFO - Running main action method: clobber 21:46:58 INFO - rmtree: /builds/slave/test/build 21:46:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:46:59 INFO - Running post-action listener: _resource_record_post_action 21:46:59 INFO - [mozharness: 2016-10-27 04:46:59.164455Z] Finished clobber step (success) 21:46:59 INFO - [mozharness: 2016-10-27 04:46:59.164554Z] Running read-buildbot-config step. 21:46:59 INFO - Running pre-action listener: _resource_record_pre_action 21:46:59 INFO - Running main action method: read_buildbot_config 21:46:59 INFO - Using buildbot properties: 21:46:59 INFO - { 21:46:59 INFO - "project": "", 21:46:59 INFO - "product": "firefox", 21:46:59 INFO - "script_repo_revision": "production", 21:46:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 21:46:59 INFO - "repository": "", 21:46:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 21:46:59 INFO - "buildid": "20161026185434", 21:46:59 INFO - "pgo_build": "True", 21:46:59 INFO - "basedir": "/builds/slave/test", 21:46:59 INFO - "buildnumber": 172, 21:46:59 INFO - "slavename": "talos-linux64-ix-062", 21:46:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:46:59 INFO - "platform": "ubuntu64_hw", 21:46:59 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 21:46:59 INFO - "branch": "mozilla-beta", 21:46:59 INFO - "repo_path": "releases/mozilla-beta", 21:46:59 INFO - "revision": "16e7406cee3df74471a05e6fe02f21f654c57bef", 21:46:59 INFO - "stage_platform": "linux64-pgo", 21:46:59 INFO - "builduid": "7dffd3c580634f41a327d661d6ea4bb0", 21:46:59 INFO - "slavebuilddir": "test" 21:46:59 INFO - } 21:46:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 21:46:59 INFO - Running post-action listener: _resource_record_post_action 21:46:59 INFO - [mozharness: 2016-10-27 04:46:59.166981Z] Finished read-buildbot-config step (success) 21:46:59 INFO - [mozharness: 2016-10-27 04:46:59.167069Z] Running download-and-extract step. 21:46:59 INFO - Running pre-action listener: _resource_record_pre_action 21:46:59 INFO - Running main action method: download_and_extract 21:46:59 INFO - mkdir: /builds/slave/test/build/tests 21:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:46:59 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:46:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:46:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:46:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:46:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:46:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:46:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:46:59 INFO - retry: Failed, sleeping 30 seconds before retrying 21:47:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:47:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:47:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:47:29 INFO - retry: Failed, sleeping 60 seconds before retrying 21:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:48:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:29 INFO - Caught exception: HTTP Error 404: Not Found 21:48:29 INFO - Caught exception: HTTP Error 404: Not Found 21:48:29 INFO - Caught exception: HTTP Error 404: Not Found 21:48:29 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:29 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:29 INFO - Downloaded 1327 bytes. 21:48:29 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 21:48:29 INFO - Using the following test package requirements: 21:48:29 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:48:29 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 21:48:29 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 21:48:29 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'jsshell-linux-x86_64.zip'], 21:48:29 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 21:48:29 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 21:48:29 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 21:48:29 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 21:48:29 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 21:48:29 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 21:48:29 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 21:48:29 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:48:29 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:48:29 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:48:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:48:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:48:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:48:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:48:29 INFO - retry: Failed, sleeping 30 seconds before retrying 21:48:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:48:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:48:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:48:59 INFO - retry: Failed, sleeping 60 seconds before retrying 21:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:49:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:49:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:49:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:49:59 INFO - Caught exception: HTTP Error 404: Not Found 21:49:59 INFO - Caught exception: HTTP Error 404: Not Found 21:49:59 INFO - Caught exception: HTTP Error 404: Not Found 21:49:59 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:49:59 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:49:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:50:00 INFO - Downloaded 21788698 bytes. 21:50:00 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:50:00 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:50:02 INFO - Return code: 0 21:50:02 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:50:02 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:50:02 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:50:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:50:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:50:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:50:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:50:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:50:02 INFO - retry: Failed, sleeping 30 seconds before retrying 21:50:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:50:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:50:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:50:32 INFO - retry: Failed, sleeping 60 seconds before retrying 21:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:51:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:51:32 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:51:32 INFO - Caught exception: HTTP Error 404: Not Found 21:51:32 INFO - Caught exception: HTTP Error 404: Not Found 21:51:32 INFO - Caught exception: HTTP Error 404: Not Found 21:51:32 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 21:51:32 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:51:32 INFO - Downloaded 21788698 bytes. 21:51:32 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:51:32 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:51:34 INFO - Return code: 0 21:51:34 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:51:34 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:51:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:51:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:51:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:51:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:51:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:51:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:51:34 INFO - retry: Failed, sleeping 30 seconds before retrying 21:52:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:52:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:52:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:52:04 INFO - retry: Failed, sleeping 60 seconds before retrying 21:53:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:53:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:53:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:04 INFO - Caught exception: HTTP Error 404: Not Found 21:53:04 INFO - Caught exception: HTTP Error 404: Not Found 21:53:04 INFO - Caught exception: HTTP Error 404: Not Found 21:53:04 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 21:53:04 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:05 INFO - Downloaded 14413762 bytes. 21:53:05 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:53:05 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:53:05 INFO - Return code: 0 21:53: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:53:05 INFO - https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:53:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:53:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:53:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:53:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 21:53:05 INFO - retry: Failed, sleeping 30 seconds before retrying 21:53:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:53:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:53:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 21:53:35 INFO - retry: Failed, sleeping 60 seconds before retrying 21:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:54:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 21:54:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:54:35 INFO - Caught exception: HTTP Error 404: Not Found 21:54:35 INFO - Caught exception: HTTP Error 404: Not Found 21:54:35 INFO - Caught exception: HTTP Error 404: Not Found 21:54:35 INFO - trying https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:35 INFO - Downloading https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/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:54:36 INFO - Downloaded 56798519 bytes. 21:54:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:36 INFO - mkdir: /builds/slave/test/properties 21:54:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:54:36 INFO - Writing to file /builds/slave/test/properties/build_url 21:54:36 INFO - Contents: 21:54:36 INFO - build_url:https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 21:54:37 INFO - Running post-action listener: _resource_record_post_action 21:54:37 INFO - Running post-action listener: set_extra_try_arguments 21:54:37 INFO - [mozharness: 2016-10-27 04:54:37.397999Z] Finished download-and-extract step (success) 21:54:37 INFO - [mozharness: 2016-10-27 04:54:37.398230Z] Running populate-webroot step. 21:54:37 INFO - Running pre-action listener: _resource_record_pre_action 21:54:37 INFO - Running main action method: populate_webroot 21:54:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:54:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:54:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:54:37 INFO - '--tppagecycles', 21:54:37 INFO - '7'], 21:54:37 INFO - 'tests': ['tsvgm']}}, 21:54:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:54:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:54:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:54:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:54:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:54:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['damp', 'tps']}, 21:54:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'tests': ['damp', 'tps']}, 21:54:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['dromaeo_dom']}, 21:54:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:54:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['basic_compositor_video']}, 21:54:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:54:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['a11yr', 21:54:37 INFO - 'ts_paint', 21:54:37 INFO - 'tpaint', 21:54:37 INFO - 'sessionrestore', 21:54:37 INFO - 'sessionrestore_no_auto_restore', 21:54:37 INFO - 'tabpaint']}, 21:54:37 INFO - 'other-e10s': {'tests': ['a11yr', 21:54:37 INFO - 'ts_paint', 21:54:37 INFO - 'tpaint', 21:54:37 INFO - 'sessionrestore', 21:54:37 INFO - 'sessionrestore_no_auto_restore', 21:54:37 INFO - 'tabpaint']}, 21:54:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:54:37 INFO - 'ts_paint', 21:54:37 INFO - 'tpaint', 21:54:37 INFO - 'sessionrestore', 21:54:37 INFO - 'sessionrestore_no_auto_restore', 21:54:37 INFO - 'tabpaint']}, 21:54:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:54:37 INFO - 'ts_paint', 21:54:37 INFO - 'tpaint', 21:54:37 INFO - 'sessionrestore', 21:54:37 INFO - 'sessionrestore_no_auto_restore', 21:54:37 INFO - 'tabpaint']}, 21:54:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['a11yr', 21:54:37 INFO - 'ts_paint', 21:54:37 INFO - 'tpaint', 21:54:37 INFO - 'sessionrestore', 21:54:37 INFO - 'sessionrestore_no_auto_restore', 21:54:37 INFO - 'tabpaint']}, 21:54:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['a11yr', 21:54:37 INFO - 'ts_paint', 21:54:37 INFO - 'tpaint', 21:54:37 INFO - 'sessionrestore', 21:54:37 INFO - 'sessionrestore_no_auto_restore', 21:54:37 INFO - 'tabpaint']}, 21:54:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['tsvgx', 21:54:37 INFO - 'tsvgr_opacity', 21:54:37 INFO - 'tart', 21:54:37 INFO - 'tscrollx', 21:54:37 INFO - 'cart']}, 21:54:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:54:37 INFO - 'tsvgr_opacity', 21:54:37 INFO - 'tart', 21:54:37 INFO - 'tscrollx', 21:54:37 INFO - 'cart']}, 21:54:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'talos_options': ['--disable-e10s'], 21:54:37 INFO - 'tests': ['tp5o']}, 21:54:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'tests': ['tp5o']}, 21:54:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'talos_options': ['--disable-e10s', 21:54:37 INFO - '--xperf_path', 21:54:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:37 INFO - 'tests': ['tp5n']}, 21:54:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:54:37 INFO - 'talos_options': ['--xperf_path', 21:54:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:54:37 INFO - 'tests': ['tp5n']}}, 21:54:37 INFO - 'talos.zip': {'path': '', 21:54:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:54:37 INFO - Running post-action listener: _resource_record_post_action 21:54:37 INFO - [mozharness: 2016-10-27 04:54:37.418250Z] Finished populate-webroot step (success) 21:54:37 INFO - [mozharness: 2016-10-27 04:54:37.418339Z] Running create-virtualenv step. 21:54:37 INFO - Running pre-action listener: _resource_record_pre_action 21:54:37 INFO - Running main action method: create_virtualenv 21:54:37 INFO - Creating virtualenv /builds/slave/test/build/venv 21:54:37 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:54:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:54:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:54:37 INFO - Using real prefix '/usr' 21:54:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:54:38 INFO - Installing distribute.............................................................................................................................................................................................done. 21:54:39 INFO - Installing pip.................done. 21:54:39 INFO - Return code: 0 21:54:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:54:39 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:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:39 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:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:39 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:39 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:54:39 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:54:39 INFO - Using env: {'DISPLAY': ':0', 21:54:39 INFO - 'HOME': '/home/cltbld', 21:54:39 INFO - 'LANG': 'en_US.UTF-8', 21:54:39 INFO - 'LANGUAGE': 'en_US:en', 21:54:39 INFO - 'LOGNAME': 'cltbld', 21:54:39 INFO - 'MAIL': '/var/mail/cltbld', 21:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:39 INFO - 'MOZ_NO_REMOTE': '1', 21:54:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:39 INFO - 'NO_EM_RESTART': '1', 21:54:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:39 INFO - 'PWD': '/builds/slave/test', 21:54:39 INFO - 'SHELL': '/bin/bash', 21:54:39 INFO - 'SHLVL': '1', 21:54:39 INFO - 'TERM': 'linux', 21:54:39 INFO - 'TMOUT': '86400', 21:54:39 INFO - 'USER': 'cltbld', 21:54:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:39 INFO - '_': '/tools/buildbot/bin/python'} 21:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:40 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:54:40 INFO - Cleaning up... 21:54:40 INFO - Return code: 0 21:54:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:54: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:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:54:40 INFO - Using env: {'DISPLAY': ':0', 21:54:40 INFO - 'HOME': '/home/cltbld', 21:54:40 INFO - 'LANG': 'en_US.UTF-8', 21:54:40 INFO - 'LANGUAGE': 'en_US:en', 21:54:40 INFO - 'LOGNAME': 'cltbld', 21:54:40 INFO - 'MAIL': '/var/mail/cltbld', 21:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:40 INFO - 'MOZ_NO_REMOTE': '1', 21:54:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:40 INFO - 'NO_EM_RESTART': '1', 21:54:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:40 INFO - 'PWD': '/builds/slave/test', 21:54:40 INFO - 'SHELL': '/bin/bash', 21:54:40 INFO - 'SHLVL': '1', 21:54:40 INFO - 'TERM': 'linux', 21:54:40 INFO - 'TMOUT': '86400', 21:54:40 INFO - 'USER': 'cltbld', 21:54:40 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:40 INFO - '_': '/tools/buildbot/bin/python'} 21:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:40 INFO - Downloading/unpacking psutil>=3.1.1 21:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:54:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:42 INFO - Installing collected packages: psutil 21:54:42 INFO - Running setup.py install for psutil 21:54:42 INFO - building 'psutil._psutil_linux' extension 21:54:42 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:54:42 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:54:42 INFO - building 'psutil._psutil_posix' extension 21:54:42 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:54:42 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:54:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:54:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:54:42 INFO - Successfully installed psutil 21:54:42 INFO - Cleaning up... 21:54:43 INFO - Return code: 0 21:54:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:54:43 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:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:43 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:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:43 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:43 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:54:43 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:54:43 INFO - Using env: {'DISPLAY': ':0', 21:54:43 INFO - 'HOME': '/home/cltbld', 21:54:43 INFO - 'LANG': 'en_US.UTF-8', 21:54:43 INFO - 'LANGUAGE': 'en_US:en', 21:54:43 INFO - 'LOGNAME': 'cltbld', 21:54:43 INFO - 'MAIL': '/var/mail/cltbld', 21:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:43 INFO - 'MOZ_NO_REMOTE': '1', 21:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:43 INFO - 'NO_EM_RESTART': '1', 21:54:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:43 INFO - 'PWD': '/builds/slave/test', 21:54:43 INFO - 'SHELL': '/bin/bash', 21:54:43 INFO - 'SHLVL': '1', 21:54:43 INFO - 'TERM': 'linux', 21:54:43 INFO - 'TMOUT': '86400', 21:54:43 INFO - 'USER': 'cltbld', 21:54:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:43 INFO - '_': '/tools/buildbot/bin/python'} 21:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:54:45 INFO - Installing collected packages: mozsystemmonitor 21:54:45 INFO - Running setup.py install for mozsystemmonitor 21:54:45 INFO - Successfully installed mozsystemmonitor 21:54:45 INFO - Cleaning up... 21:54:45 INFO - Return code: 0 21:54:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:54:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:54:45 INFO - Using env: {'DISPLAY': ':0', 21:54:45 INFO - 'HOME': '/home/cltbld', 21:54:45 INFO - 'LANG': 'en_US.UTF-8', 21:54:45 INFO - 'LANGUAGE': 'en_US:en', 21:54:45 INFO - 'LOGNAME': 'cltbld', 21:54:45 INFO - 'MAIL': '/var/mail/cltbld', 21:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:45 INFO - 'MOZ_NO_REMOTE': '1', 21:54:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:45 INFO - 'NO_EM_RESTART': '1', 21:54:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:45 INFO - 'PWD': '/builds/slave/test', 21:54:45 INFO - 'SHELL': '/bin/bash', 21:54:45 INFO - 'SHLVL': '1', 21:54:45 INFO - 'TERM': 'linux', 21:54:45 INFO - 'TMOUT': '86400', 21:54:45 INFO - 'USER': 'cltbld', 21:54:45 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:45 INFO - '_': '/tools/buildbot/bin/python'} 21:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:45 INFO - Downloading/unpacking jsonschema==2.5.1 21:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:54:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:54:47 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:54:47 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:54:47 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:54:47 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:54:47 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:54:47 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:54:47 INFO - Downloading functools32-3.2.3-2.tar.gz 21:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:54:47 INFO - warning: no files found matching '*.txt' 21:54:47 INFO - no previously-included directories found matching 'build' 21:54:47 INFO - no previously-included directories found matching 'dist' 21:54:47 INFO - no previously-included directories found matching '.git*' 21:54:47 INFO - Installing collected packages: jsonschema, functools32 21:54:47 INFO - Running setup.py install for functools32 21:54:47 INFO - warning: no files found matching '*.txt' 21:54:47 INFO - no previously-included directories found matching 'build' 21:54:47 INFO - no previously-included directories found matching 'dist' 21:54:47 INFO - no previously-included directories found matching '.git*' 21:54:47 INFO - Successfully installed jsonschema functools32 21:54:47 INFO - Cleaning up... 21:54:47 INFO - Return code: 0 21:54:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:54:47 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:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:47 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:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:47 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:47 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:54:47 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:54:47 INFO - Using env: {'DISPLAY': ':0', 21:54:47 INFO - 'HOME': '/home/cltbld', 21:54:47 INFO - 'LANG': 'en_US.UTF-8', 21:54:47 INFO - 'LANGUAGE': 'en_US:en', 21:54:47 INFO - 'LOGNAME': 'cltbld', 21:54:47 INFO - 'MAIL': '/var/mail/cltbld', 21:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:47 INFO - 'MOZ_NO_REMOTE': '1', 21:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:47 INFO - 'NO_EM_RESTART': '1', 21:54:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:47 INFO - 'PWD': '/builds/slave/test', 21:54:47 INFO - 'SHELL': '/bin/bash', 21:54:47 INFO - 'SHLVL': '1', 21:54:47 INFO - 'TERM': 'linux', 21:54:47 INFO - 'TMOUT': '86400', 21:54:47 INFO - 'USER': 'cltbld', 21:54:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:47 INFO - '_': '/tools/buildbot/bin/python'} 21:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:54:48 INFO - Cleaning up... 21:54:48 INFO - Return code: 0 21:54:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:54:48 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:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:48 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:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:48 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:48 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:54:48 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:54:48 INFO - Using env: {'DISPLAY': ':0', 21:54:48 INFO - 'HOME': '/home/cltbld', 21:54:48 INFO - 'LANG': 'en_US.UTF-8', 21:54:48 INFO - 'LANGUAGE': 'en_US:en', 21:54:48 INFO - 'LOGNAME': 'cltbld', 21:54:48 INFO - 'MAIL': '/var/mail/cltbld', 21:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:48 INFO - 'MOZ_NO_REMOTE': '1', 21:54:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:48 INFO - 'NO_EM_RESTART': '1', 21:54:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:48 INFO - 'PWD': '/builds/slave/test', 21:54:48 INFO - 'SHELL': '/bin/bash', 21:54:48 INFO - 'SHLVL': '1', 21:54:48 INFO - 'TERM': 'linux', 21:54:48 INFO - 'TMOUT': '86400', 21:54:48 INFO - 'USER': 'cltbld', 21:54:48 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:48 INFO - '_': '/tools/buildbot/bin/python'} 21:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:48 INFO - Downloading/unpacking blobuploader==1.2.4 21:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:54:50 INFO - Downloading blobuploader-1.2.4.tar.gz 21:54:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:54:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:54:50 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:54:50 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:54:50 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:54:50 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:54:50 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:54:50 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:54:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:54:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:54:50 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:54:50 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:54:50 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:54:50 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:54:50 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:54:50 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:54:51 INFO - Downloading docopt-0.6.1.tar.gz 21:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:54:51 INFO - Installing collected packages: blobuploader, requests, docopt 21:54:51 INFO - Running setup.py install for blobuploader 21:54:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:54:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:54:51 INFO - Running setup.py install for requests 21:54:51 INFO - Running setup.py install for docopt 21:54:51 INFO - Successfully installed blobuploader requests docopt 21:54:51 INFO - Cleaning up... 21:54:51 INFO - Return code: 0 21:54:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54: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:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:51 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:51 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:54:51 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:54:51 INFO - Using env: {'DISPLAY': ':0', 21:54:51 INFO - 'HOME': '/home/cltbld', 21:54:51 INFO - 'LANG': 'en_US.UTF-8', 21:54:51 INFO - 'LANGUAGE': 'en_US:en', 21:54:51 INFO - 'LOGNAME': 'cltbld', 21:54:51 INFO - 'MAIL': '/var/mail/cltbld', 21:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:51 INFO - 'MOZ_NO_REMOTE': '1', 21:54:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:51 INFO - 'NO_EM_RESTART': '1', 21:54:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:51 INFO - 'PWD': '/builds/slave/test', 21:54:51 INFO - 'SHELL': '/bin/bash', 21:54:51 INFO - 'SHLVL': '1', 21:54:51 INFO - 'TERM': 'linux', 21:54:51 INFO - 'TMOUT': '86400', 21:54:51 INFO - 'USER': 'cltbld', 21:54:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:51 INFO - '_': '/tools/buildbot/bin/python'} 21:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:54:51 INFO - Running setup.py (path:/tmp/pip-dZfF9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:54:52 INFO - Running setup.py (path:/tmp/pip-yQCgyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:54:52 INFO - Running setup.py (path:/tmp/pip-C6TsNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:54:52 INFO - Running setup.py (path:/tmp/pip-mo6qR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:54:52 INFO - Running setup.py (path:/tmp/pip-yisy49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:54:52 INFO - Running setup.py (path:/tmp/pip-ofb5D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:54:52 INFO - Running setup.py (path:/tmp/pip-8BBdcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:54:52 INFO - Running setup.py (path:/tmp/pip-arjdMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:54:52 INFO - Running setup.py (path:/tmp/pip-bFz1ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:54:52 INFO - Running setup.py (path:/tmp/pip-jlElkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:54:52 INFO - Running setup.py (path:/tmp/pip-ispT07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:54:52 INFO - Running setup.py (path:/tmp/pip-JlMBul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:54:52 INFO - Running setup.py (path:/tmp/pip-GvJ3t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:54:52 INFO - Running setup.py (path:/tmp/pip-zCUYXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:52 INFO - Running setup.py (path:/tmp/pip-vwPS4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:54:53 INFO - Running setup.py (path:/tmp/pip-Lp5VES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:54:53 INFO - Running setup.py (path:/tmp/pip-OUYAw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:54:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:54:53 INFO - Running setup.py install for manifestparser 21:54:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:54:53 INFO - Running setup.py install for mozcrash 21:54:53 INFO - Running setup.py install for mozdebug 21:54:53 INFO - Running setup.py install for mozdevice 21:54:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:54:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:54:53 INFO - Running setup.py install for mozfile 21:54:53 INFO - Running setup.py install for mozhttpd 21:54:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:54:53 INFO - Running setup.py install for mozinfo 21:54:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:54:53 INFO - Running setup.py install for mozInstall 21:54:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Running setup.py install for mozleak 21:54:54 INFO - Running setup.py install for mozlog 21:54:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Running setup.py install for moznetwork 21:54:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Running setup.py install for mozprocess 21:54:54 INFO - Running setup.py install for mozprofile 21:54:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Running setup.py install for mozrunner 21:54:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Running setup.py install for mozscreenshot 21:54:54 INFO - Running setup.py install for moztest 21:54:54 INFO - Running setup.py install for mozversion 21:54:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:54:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:54:54 INFO - Cleaning up... 21:54:54 INFO - Return code: 0 21:54:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54: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:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54: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:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:54 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:54 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:54:54 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:54:54 INFO - Using env: {'DISPLAY': ':0', 21:54:54 INFO - 'HOME': '/home/cltbld', 21:54:54 INFO - 'LANG': 'en_US.UTF-8', 21:54:54 INFO - 'LANGUAGE': 'en_US:en', 21:54:54 INFO - 'LOGNAME': 'cltbld', 21:54:54 INFO - 'MAIL': '/var/mail/cltbld', 21:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:54 INFO - 'MOZ_NO_REMOTE': '1', 21:54:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:54 INFO - 'NO_EM_RESTART': '1', 21:54:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:54 INFO - 'PWD': '/builds/slave/test', 21:54:54 INFO - 'SHELL': '/bin/bash', 21:54:54 INFO - 'SHLVL': '1', 21:54:54 INFO - 'TERM': 'linux', 21:54:54 INFO - 'TMOUT': '86400', 21:54:54 INFO - 'USER': 'cltbld', 21:54:54 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:55 INFO - '_': '/tools/buildbot/bin/python'} 21:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:54:55 INFO - Running setup.py (path:/tmp/pip-OnFY0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:54:55 INFO - Running setup.py (path:/tmp/pip-jjy4Qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:54:55 INFO - Running setup.py (path:/tmp/pip-E0LlFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:54:55 INFO - Running setup.py (path:/tmp/pip-3BCqcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:54:55 INFO - Running setup.py (path:/tmp/pip-_7ftCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:54:55 INFO - Running setup.py (path:/tmp/pip-Aq_JWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:54:55 INFO - Running setup.py (path:/tmp/pip-xgPciN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:54:55 INFO - Running setup.py (path:/tmp/pip-a8mu29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:54:55 INFO - Running setup.py (path:/tmp/pip-kSIxvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:54:55 INFO - Running setup.py (path:/tmp/pip-85NoPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:54:55 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:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:54:55 INFO - Running setup.py (path:/tmp/pip-osBT7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:54:56 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:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:54:56 INFO - Running setup.py (path:/tmp/pip-GGBAF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:54:56 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:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:54:56 INFO - Running setup.py (path:/tmp/pip-YtVJmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:54:56 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:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:54:56 INFO - Running setup.py (path:/tmp/pip-hT4q5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:54:56 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:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:56 INFO - Running setup.py (path:/tmp/pip-FmWghU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:54:56 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:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:54:56 INFO - Running setup.py (path:/tmp/pip-LBfh01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:54:56 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:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:54:56 INFO - Running setup.py (path:/tmp/pip-8o8YEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:54:56 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:54:56 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:54:56 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:54:56 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:54:56 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:54:56 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:54:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:54: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:54: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:54: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:54: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:54: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:54: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:54:58 INFO - Downloading blessings-1.6.tar.gz 21:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:54:58 INFO - Installing collected packages: blessings 21:54:58 INFO - Running setup.py install for blessings 21:54:58 INFO - Successfully installed blessings 21:54:58 INFO - Cleaning up... 21:54:58 INFO - Return code: 0 21:54:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:54:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:54:58 INFO - Reading from file tmpfile_stdout 21:54:58 INFO - Current package versions: 21:54:58 INFO - argparse == 1.2.1 21:54:58 INFO - blessings == 1.6 21:54:58 INFO - blobuploader == 1.2.4 21:54:58 INFO - docopt == 0.6.1 21:54:58 INFO - functools32 == 3.2.3-2 21:54:58 INFO - jsonschema == 2.5.1 21:54:58 INFO - manifestparser == 1.1 21:54:58 INFO - mozInstall == 1.12 21:54:58 INFO - mozcrash == 0.17 21:54:58 INFO - mozdebug == 0.1 21:54:58 INFO - mozdevice == 0.48 21:54:58 INFO - mozfile == 1.2 21:54:58 INFO - mozhttpd == 0.7 21:54:58 INFO - mozinfo == 0.9 21:54:58 INFO - mozleak == 0.1 21:54:58 INFO - mozlog == 3.3 21:54:58 INFO - moznetwork == 0.27 21:54:58 INFO - mozprocess == 0.23 21:54:58 INFO - mozprofile == 0.28 21:54:58 INFO - mozrunner == 6.12 21:54:58 INFO - mozscreenshot == 0.1 21:54:58 INFO - mozsystemmonitor == 0.3 21:54:58 INFO - moztest == 0.7 21:54:58 INFO - mozversion == 1.4 21:54:58 INFO - psutil == 3.1.1 21:54:58 INFO - requests == 1.2.3 21:54:58 INFO - wsgiref == 0.1.2 21:54:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:54:58 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:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:58 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:54:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:58 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 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:58 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:54:58 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:54:58 INFO - Using env: {'DISPLAY': ':0', 21:54:58 INFO - 'HOME': '/home/cltbld', 21:54:58 INFO - 'LANG': 'en_US.UTF-8', 21:54:58 INFO - 'LANGUAGE': 'en_US:en', 21:54:58 INFO - 'LOGNAME': 'cltbld', 21:54:58 INFO - 'MAIL': '/var/mail/cltbld', 21:54:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:58 INFO - 'MOZ_NO_REMOTE': '1', 21:54:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:58 INFO - 'NO_EM_RESTART': '1', 21:54:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:58 INFO - 'PWD': '/builds/slave/test', 21:54:58 INFO - 'SHELL': '/bin/bash', 21:54:58 INFO - 'SHLVL': '1', 21:54:58 INFO - 'TERM': 'linux', 21:54:58 INFO - 'TMOUT': '86400', 21:54:58 INFO - 'USER': 'cltbld', 21:54:58 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:58 INFO - '_': '/tools/buildbot/bin/python'} 21:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 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:54:59 INFO - Cleaning up... 21:54:59 INFO - Return code: 0 21:54:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:54:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27f5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', '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:54:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:54:59 INFO - Using env: {'DISPLAY': ':0', 21:54:59 INFO - 'HOME': '/home/cltbld', 21:54:59 INFO - 'LANG': 'en_US.UTF-8', 21:54:59 INFO - 'LANGUAGE': 'en_US:en', 21:54:59 INFO - 'LOGNAME': 'cltbld', 21:54:59 INFO - 'MAIL': '/var/mail/cltbld', 21:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:54:59 INFO - 'MOZ_NO_REMOTE': '1', 21:54:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:54:59 INFO - 'NO_EM_RESTART': '1', 21:54:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:54:59 INFO - 'PWD': '/builds/slave/test', 21:54:59 INFO - 'SHELL': '/bin/bash', 21:54:59 INFO - 'SHLVL': '1', 21:54:59 INFO - 'TERM': 'linux', 21:54:59 INFO - 'TMOUT': '86400', 21:54:59 INFO - 'USER': 'cltbld', 21:54:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:54:59 INFO - '_': '/tools/buildbot/bin/python'} 21:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:54:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:54:59 INFO - Cleaning up... 21:54:59 INFO - Return code: 0 21:54:59 INFO - Running post-action listener: _resource_record_post_action 21:54:59 INFO - Running post-action listener: _start_resource_monitoring 21:54:59 INFO - Starting resource monitoring. 21:54:59 INFO - [mozharness: 2016-10-27 04:54:59.337394Z] Finished create-virtualenv step (success) 21:54:59 INFO - [mozharness: 2016-10-27 04:54:59.337826Z] Running install step. 21:54:59 INFO - Running pre-action listener: _resource_record_pre_action 21:54:59 INFO - Running main action method: install 21:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:54:59 INFO - Reading from file tmpfile_stdout 21:54:59 INFO - Detecting whether we're running mozinstall >=1.0... 21:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:54:59 INFO - Reading from file tmpfile_stdout 21:54:59 INFO - Output received: 21:54:59 INFO - Usage: mozinstall [options] installer 21:54:59 INFO - Options: 21:54:59 INFO - -h, --help show this help message and exit 21:54:59 INFO - -d DEST, --destination=DEST 21:54:59 INFO - Directory to install application into. [default: 21:54:59 INFO - "/builds/slave/test"] 21:54:59 INFO - --app=APP Application being installed. [default: firefox] 21:54:59 INFO - mkdir: /builds/slave/test/build/application 21:54:59 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:54:59 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:55:13 INFO - Reading from file tmpfile_stdout 21:55:13 INFO - Output received: 21:55:13 INFO - /builds/slave/test/build/application/firefox/firefox 21:55:13 INFO - Running post-action listener: _resource_record_post_action 21:55:13 INFO - [mozharness: 2016-10-27 04:55:13.663703Z] Finished install step (success) 21:55:13 INFO - [mozharness: 2016-10-27 04:55:13.663983Z] Running run-tests step. 21:55:13 INFO - Running pre-action listener: _resource_record_pre_action 21:55:13 INFO - Running main action method: run_tests 21:55:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:55:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:55:13 INFO - Python 2.7.3 21:55:13 INFO - Return code: 0 21:55:13 INFO - grabbing minidump binary from tooltool 21:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:13 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 0x2884570>, '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 0x2885470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2885900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:55:13 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:55:13 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:55:13 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:55:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:55:13 INFO - Return code: 0 21:55:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:55:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:55:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:55:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:55:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:55:13 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:55:13 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:55:13 INFO - Using env: {'DISPLAY': ':0', 21:55:13 INFO - 'HOME': '/home/cltbld', 21:55:13 INFO - 'LANG': 'en_US.UTF-8', 21:55:13 INFO - 'LANGUAGE': 'en_US:en', 21:55:13 INFO - 'LOGNAME': 'cltbld', 21:55:13 INFO - 'MAIL': '/var/mail/cltbld', 21:55:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:55:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:13 INFO - 'MOZ_NO_REMOTE': '1', 21:55:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:55:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:13 INFO - 'NO_EM_RESTART': '1', 21:55:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:55:13 INFO - 'PWD': '/builds/slave/test', 21:55:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:55:13 INFO - 'SHELL': '/bin/bash', 21:55:13 INFO - 'SHLVL': '1', 21:55:13 INFO - 'TERM': 'linux', 21:55:13 INFO - 'TMOUT': '86400', 21:55:13 INFO - 'USER': 'cltbld', 21:55:13 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425', 21:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:13 INFO - '_': '/tools/buildbot/bin/python'} 21:55:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:55:13 INFO - mozversion application_buildid: 20161026185434 21:55:13 INFO - mozversion application_changeset: 16e7406cee3df74471a05e6fe02f21f654c57bef 21:55:13 INFO - mozversion application_display_name: Firefox 21:55:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:55:13 INFO - mozversion application_name: Firefox 21:55:13 INFO - mozversion application_remotingname: firefox 21:55:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 21:55:13 INFO - mozversion application_vendor: Mozilla 21:55:13 INFO - mozversion application_version: 50.0 21:55:13 INFO - mozversion platform_buildid: 20161026185434 21:55:13 INFO - mozversion platform_changeset: 16e7406cee3df74471a05e6fe02f21f654c57bef 21:55:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 21:55:13 INFO - mozversion platform_version: 50.0 21:55:13 INFO - using testdate: 1477544113 21:55:13 INFO - actual date: 1477544113 21:55:13 INFO - starting webserver on 'localhost:46455' 21:55:13 INFO - SUITE-START | Running 2 tests 21:55:13 INFO - TEST-START | dromaeo_css 21:55:13 INFO - operating with platform_type : linux_ 21:55:13 INFO - Initialising browser for dromaeo_css test... 21:55:13 INFO - TEST-INFO | started process 20386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCExJs2/profile http://localhost:46455/getInfo.html) 21:55:17 INFO - PROCESS | 20386 | __metrics Screen width/height:1600/1200 21:55:17 INFO - PROCESS | 20386 | colorDepth:24 21:55:17 INFO - PROCESS | 20386 | Browser inner width/height: 1024/697 21:55:17 INFO - PROCESS | 20386 | __metrics 21:55:18 ERROR - PROCESS | 20386 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 21:55:19 INFO - TEST-INFO | 20386: exit 0 21:55:19 INFO - Browser initialized. 21:55:19 INFO - Running cycle 1/1 for dromaeo_css test... 21:55:19 INFO - TEST-INFO | started process 20512 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCExJs2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:55:31 INFO - PROCESS | 20512 | RSS: Main: 150429696 21:55:31 INFO - PROCESS | 20512 | 21:56:20 INFO - PROCESS | 20512 | 1477544180598 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:58:28 INFO - PROCESS | 20512 | Cycle 1(1): loaded http://localhost:46455/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46455/tests/dromaeo/cssquery-ext.html) 21:58:29 INFO - PROCESS | 20512 | RSS: Main: 159006720 21:58:29 INFO - PROCESS | 20512 | 22:01:31 INFO - PROCESS | 20512 | Cycle 1(1): loaded http://localhost:46455/tests/dromaeo/cssquery-ext.html (next: http://localhost:46455/tests/dromaeo/cssquery-jquery.html) 22:01:32 INFO - PROCESS | 20512 | RSS: Main: 155791360 22:01:32 INFO - PROCESS | 20512 | 22:04:34 INFO - PROCESS | 20512 | Cycle 1(1): loaded http://localhost:46455/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46455/tests/dromaeo/cssquery-mootools.html) 22:04:34 INFO - PROCESS | 20512 | RSS: Main: 155561984 22:04:34 INFO - PROCESS | 20512 | 22:07:36 INFO - PROCESS | 20512 | Cycle 1(1): loaded http://localhost:46455/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46455/tests/dromaeo/cssquery-prototype.html) 22:07:37 INFO - PROCESS | 20512 | RSS: Main: 156868608 22:07:37 INFO - PROCESS | 20512 | 22:10:39 INFO - PROCESS | 20512 | Cycle 1(1): loaded http://localhost:46455/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46455/tests/dromaeo/cssquery-yui.html) 22:10:40 INFO - PROCESS | 20512 | RSS: Main: 157683712 22:10:40 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | Cycle 1(1): loaded http://localhost:46455/tests/dromaeo/cssquery-yui.html (next: http://localhost:46455/tests/dromaeo/cssquery-dojo.html) 22:13:42 INFO - PROCESS | 20512 | RSS: Main: 157839360 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | __start_tp_report 22:13:42 INFO - PROCESS | 20512 | _x_x_mozilla_page_load 22:13:42 INFO - PROCESS | 20512 | _x_x_mozilla_page_load_details 22:13:42 INFO - PROCESS | 20512 | |i|pagename|runs| 22:13:42 INFO - PROCESS | 20512 | |0;dojo.html;3219.967800321997;3224.6452890182077;3245.8539365728557;3264.9836750816244;3268.1176082457737;5691.971540142299;5769.423057694228;5772.105323674838;5775.3358363788075;5805.593608447411;5592.328920529532;5684.317881854184;5708.486236238738;5709.628874123179;5718.228039214705;5142.845714628567;5164.225366195082;5165.04446677364;5207.375114986188;5215.1395019821875;5159.793608255665;5160.432352441228;5161.200013997836;5220.791168353261;5273.209018647195;5022.874428139307;5110.540051395374;5120.078385890538;5128.025675121715;5175.534201921826;5079.542841144296;5204.34945631796;5236.083685355047;5251.317328747276;5270.894582108374;5154.304168937183;5182.559482443997;5197.58419326453;5202.479752024768;5208.8437346879655;5422.43064478232;5484.7531861065945;5598.720063996825;5686.431356864322;5758.481736643722;5944.048952167675;5988.341282458926;5994.760209591611;6052.515798736091;6086.665233412161;6017.819465416044;6018.578699491038;6030.18592490017;6036.3360030396625;6077.088436734481;5505.366882808488;5546.805861794898;5570.41510641385;5628.577856660726;5651.547876169938;6099.573029887954;6175.752969881154;6185.969070154621;6188.907166392508;6191;5917.644941303535;5955.702214889238;5966.164736936832;5974.940250597525;5989.8203053908455;5802.82591522255;5971.4028597140805;6020;6044.214252147281;6057.091436284592;6032.276126864805;6109.313572000111;6134.40597271149;6147.0779383092895;6157.014877619559;6107.463072948034;6117.957087457094;6119.350229749377;6124.229237387393;6153.753849846045;4508.639308855284;4535.138323718483;4700.929486057712;4705.55297246761;4718.127146477913;4002.839886404511;4058.9594104059174;4078.225137223918;4083.407905853694;4094.8566800161852;5633.802816901471;5668.461496157858;5671.404502527258;5684.118961560965;5704.429557044263;5913.970430147908;5921.526277897844;5957.672328022;5964.135200395918;5965.612235204698;5721.77112915487;5737.3975732548315;5781.566382521328;5783.971080144573;5797;5231.215317702299;5262.184361423985;5313.495217954288;5327.9733601331745;5334.973325133349;6136.12084543884;6188.535859810442;6227.719752611053;6293.2762732285455;6392.936070639327;6167.537434692326;6176.907346389898;6188.9690551546955;6193.38066193377;6218.160548325918;6339.90490142639;6356.4279214870885;6379.234491861006;6403.199600049994;6403.391677790602;5826.533877289722;5864.736086876186;5906.940930590639;6044.15381846533;6063.151158837854;6092.908606370819;6136.110264011604;6138.631682099089;6141.631502109888;6151.6924153793025;5974.61165024272;5986.102084687332;6002.969985150221;6012.549058820693;6018.578699490994;5786.07422812348;5946.464818166386;5962.373950735284;5982.46157845796;6011.789587364421;5983.401659833982;6003.6397816131175;6003.939960600513;6005.339412664691;6015.909761353671;6048.395160484092;6049.637021778707;6086.056661217518;6100.176476175837;6103.084537319438;5678.602497825113;5792;5818.679972601378;5948.345681974893;5951.28584569837 22:13:42 INFO - PROCESS | 20512 | |1;ext.html;586.035970827988;634.2769243062908;649.7076315657954;667.7095463473389;707.994647600408;40355.59644403555;45045.77477112614;45123.32315015273;45141.322880156826;45218.32172517415;46012.30981535271;46471.53528464723;46753.766231168884;46895.765521172434;46995.76502117485;8856.335774816866;8934.597943092544;8940.15068568485;8950.507722075283;9034.05142460042;8914.821703565925;8947.77630559238;8973.192412682858;9008.954955225216;9027.864582031274;8990.730278091667;8993.415427997159;8996.145366190201;9048.597467392532;9092;8961.297353502814;9072.27421806254;9079.546022698838;9102.681406150752;9128.817423651557;8999.955000225022;9036.412633178888;9051.954740226323;9056.320775941766;9079.727608171765;43181.56818431807;43627.56372436298;43685;43732;43801.12397752027;3822.3502004659276;3834.2906562286066;3948.74333168346;3993.5008123984503;4207.389928460387;34224.48663270053;34227.31545369079;34523.482147767805;36373.27253454916;36449.453258201145;4395.582419670126;4486.967997360625;4502.909941801178;4608.884777880547;4609.516000819899;375021.1248943765;376939;377458;378112;378471;58378.70810646004;58406.4159358401;58411.123833142534;58495.415045849;58554.414455855745;39222.21555568872;40229.39655905164;40388.19223615511;65405.4972853526;65644;19066.2373505061;19106.33127840519;19177.137028833735;19279.036048197533;19316.613667726568;14775.335109920079;14815.555533334018;14848.628784280263;14878.702425951637;15034.173120478259;20671;20691.137797598287;20911.163553457995;20953.790462095185;21049.263275785273;7538.359239464707;7571.659275332623;7573.810654733566;7626.313631773056;7693.153753087156;1050.6637875879799;1062.293574772783;1108.2353176308482;1117.452448300327;1155.7284038251005;34002.14994625105;34212.82893585516;34218.82890585581;34415.483767743506;34811.825940870134;1158.0677554568654;1168.1122347016328;1172.6540670502052;1190.880911908802;1207.643745095199;17476.650466990588;17769.555761106076;17854.28582856697;17971.640567188584;18001.54996125081;634017;637538;648200;649238;649784.7510762416;64166.35833641604;64691.67654161793;64885.67557162184;64972.35027649758;65088.34911650823;1384.4808196926153;1426.764583843675;1441.92069436182;1463.6926245488567;1466.6846627974824;32237.838810806734;33571.32857342888;33688.66311336855;34107.65892341045;34275.657243427246;54908;58571.4142858583;60248.39751602428;60879.086813696944;65994;535705;545069;545083;546413;546555;47332.290015650484;47592.28611570899;48161;48330.275045873634;48528;51366;51922.740386297824;52460.73769631128;53496;53510.197347039044;43754;43942.34086488641;44745;44762.77618611886;44901.77549112234;43045.7847710772;44379.55620443754;44514.3322850151;44723.32915006342;45591.31613025874;43489;43975.560244398424;44569;44927;47922;38402.23195536018;38883.66697998129;39162.2167556653;39991.40012899867;40031;29570.704292957656;29709;29767.55348669815;30283.697163028086;30344.54483182798 22:13:42 INFO - PROCESS | 20512 | |2;jquery.html;419.3479139937396;422.46769070970566;432.88312155717955;435.7472665853806;435.9280718681418;18479.076046197686;19580.608387832253;20273.59452810943;20362.592748145027;20434.08046637901;19361.225550977942;19455;19719.211231550722;19789.901050494762;19963.700544491843;5718.857028574277;5960.374160713128;5961.344252132262;5966.582339236255;5975.820725378224;5854.970725146369;5859.970700146515;5862.120681897707;5952.523798096142;5970.552208584352;5576.86057848553;5851.590388672795;5865.472107510323;5875.853103672421;5877.089051197071;5783.190353350513;5796;5808.4481974212595;5812.099124635688;5826.504747096459;5207.062728708831;5219.921701174486;5222.947770522284;5226.320578324793;5234;22674.886625566767;23208.883955580113;23309.650355244685;23514.7648523516;23606.881965590233;2314.7916687498205;2400.23192578375;2536.4927014597156;2580.2259322203454;2602.4534847682007;10883.83674244887;10894;11085.50115244816;12849.807252891214;13817.101888377223;1974.3879397386713;1980.6434841728483;1984.1765667248076;2016.41523958052;2072.7409073865765;83006.58496707599;83228.58385708033;83462;83742;84130;11404.486798094105;11523.71190720222;11590.478428470571;11630.476628551736;11838.822417663741;16211.432599858948;16322.347106115622;16373.42693005739;16400.425985090464;16409.753853692204;8731.476111433334;8765.868511972325;8823.382363234512;8962.103789621116;9106.5884787858;8715.738527844049;8734.95632521846;8747.212750852326;8881.555922203994;8969.910300897038;4979.078870408914;5005.174146265826;5037;5068.847934561969;5097.617678674114;2082.4065141434617;2097.979020209809;2107.3888572314163;2132.424245453719;2156.3207589609224;985.2905907746411;1009.4750729620556;1024.206240163864;1053.910417614496;1112.593903225317;19191;19714.40856774299;19822.70265946012;19972.300969466;20148.597028059357;1044.2429238801808;1059.390850261103;1076.0853274716585;1082.0207712020633;1093.578972095749;10337.173026157889;11065.114790816715;11093.778124437627;11139.387333696563;11203.719907002227;488671;490658;498491;503755;531030;88283.55858220668;90495.09504904867;92186;92762.68093297722;95614;1025.195221750922;1031.1750599520353;1038.314712289885;1051.311391038886;1058.5871510111062;16660.250288736792;17141.142942853057;17542.298308067533;17549.912250438665;17721.379751708628;41015.794921025205;41418.37872431856;41924.790376047924;42765;43293.3505997404;57538.7123064382;57746.711266443395;57902;58125.418745813695;59368.70315648394;8957.865632015395;9008.324375671982;9121.771955700895;9203.079692030744;9257.583408746488;10418.843717344094;13390.866091339352;14168.787468187778;14206;14355.856441435451;11665.183437159656;11690.123240756808;11817.409129543317;11868.347240901488;12002;12010.939945300217;12011.219270747373;12048.216865903689;12070.637880863587;12221.327826969602;11512.712182195512;11617.76764464689;11638.825417618911;11722.472488738198;12115.515379384726;8781.604827782892;8848.20366167048;8896.021437641984;8976.685815996409;9346.439213647203;10317.793644127225;11465.598704045318;11813.881861181622;11977.700557486132;12035.819462708241 22:13:42 INFO - PROCESS | 20512 | |3;mootools.html;1559.6568754873927;1561.4847100456848;1730.5846596816768;1801.3875282403988;1859.4421673497948;8573.271271941892;9045.773855653595;9208.67769628063;9947.602095916163;9971.451570163637;9115;9440.858387124199;9449.38578992365;9452.905470945288;9459.621615135404;2490.1035627174156;2517.697876254852;2540.6697129373156;2545.6563363945907;2546.019782383777;2433.6227884677905;2548.5157820014138;2549.6430499730054;2551.719310875802;2553.1702196786027;2521.205820166651;2526.027479420427;2529.0263248649217;2534.518441496119;2544.402065514612;2441.1944058460667;2463.8275320727557;2466.0259197616842;2471.6910386201725;2473.1220416751976;2421.346236516131;2431.538007778516;2434.720007199177;2442.499287646046;2478.603423452257;9788.070133337322;9841.360311579725;9881.456519891402;9897.059779320953;9939.602415903355;5490.80782172622;5548.361938377057;5604.747786349624;5622.943770562283;5623.409541998072;3292.1275861896615;3366.5960084789735;3372.9325413491592;3437.553118094632;3456.1359660084977;1009.4548943570463;1012.4178597306504;1031.381171297223;1063.8138325792954;1067.4822710985165;13801.309934503335;13831.170129792145;13856.307184640727;13864.306784660726;13889.861101389059;4679.555442232982;4709.62323014158;4857.174280372275;4909.95090049095;4990.051890140861;11557;11561.132915031238;11571.19001669875;11586.594469193538;11621.53513859453;5635.915461268084;5684.516816070681;5684.7777727788725;5718.170865224519;5736.4550367715;4524.547545245449;5120.308758317654;5194.0650682876685;5201.687898726089;5205.167173252262;4246.320588705792;4288.742675439484;4296;4306.763128027988;4335.328024156261;1324.179009014398;1325.0856908733135;1337.5786627212397;1370.5408688089403;1382.910110842792;977.8582105594649;1074.468138271545;1075.0808059109506;1077.3212875888141;1096.0464395975553;6519.576227545194;6556.8360790980605;6726.461883049345;6750.35871592198;6767;468.920283551797;470.5226979950563;480.9206480930677;482.3488290807452;482.45723560993883;4572.862814115582;4654.604358629486;4709.670323077352;4742.264948932921;4759.904801903943;11770.646880593597;12294.692632684075;12311.261324320569;12316.630501084981;12437.93781031089;11959.880401195876;12172.087093467848;12193.756124877631;12199.634010979686;12237.20458170216;9058.411203271899;9181.173694367539;9189.540522973824;9194.586243619053;9242.584083716249;6681.265060843206;6822.215445223665;6971.5468494547695;7033.859322813412;7097.787066388017;11160.10719142483;11278.09775217997;11317.830232546354;11410.14423918193;11441.313521188755;11388.487518061873;11417.486213120595;11514.481848316678;11524.82712759326;11551.960323570918;7816;8343.332533397435;8515.957420212859;8710.12898710124;8879.200871921557;9492.667756628485;9597.568109435231;9633.373830700983;9634.421934683942;9653.806923861624;8520.10538893432;8698.391112622056;8786.912130878609;8806.634971579415;8881.644734210558;8594.742157735278;8654.48073115615;8660.17728315809;8761.474311541328;8826.293896488138;7428.182899881117;7599.696012159452;7601.923980760122;7631.007968964222;7637.083549973817;5760.8271751847515;5824;5949.6430214187285;5968.731407086605;6021.969890150521;5717.571182161438;5773.971130144322;5785.971070144622;5840.737166827418;5915.171875937286 22:13:42 INFO - PROCESS | 20512 | |4;prototype.html;338.4483292233659;348.3312040881508;352.48889110789355;356.49911873817297;360.0584471606749;5579.38626751057;5726.3700992890745;5800.622959507635;5807.73865176067;5814.912776308353;5658.688772117532;5702.173184888189;5706.7431965561545;5713.71431428428;5713.914291285634;4474.97762511187;4491.528389519102;4524.185646583614;4524.909501809978;4528.4339457567885;4380.102079073782;4451.465824101112;4490.146872094308;4491.326301054835;4499.965008048151;4443.84446544371;4471.7987690554;4476.306172543261;4476.485204201526;4480.9163738861425;4471.910561788779;4474.44069491312;4478.208716513388;4487.618552423048;4488.147252022105;3826.9234615307537;3970.166265084336;4009.3585026395635;4011.4584531088512;4019.4573732546023;5543.722813859291;5553.694546799924;5562.888742225174;5563.888722225533;5578.210894552723;5226.843194704127;5237.371515418175;5237.633365664405;5241.109011468059;5256.9474305256845;5888.852778680524;5939.70301484924;5969.224000879858;6019.909701354483;6046.425589568984;4455.754933478657;4458.4426946631675;4466.218411777926;4468.597826195658;4478.424905679215;6238.407351301619;6241.96879015602;6260.436560709558;6260.812175634738;6283.497320214417;4415.381846541422;4564.246899261585;4587.105514424722;4590.8163673453355;4602.861914142581;4663.090697313991;4673.322368256584;4673.929891051637;4678.3216433616935;4680.625549955996;5241.344831896013;5246.868828279247;5262.368515778131;5268.236105764643;5274.630775845731;5835.795747148914;5886.58793884424;5971.2834459864225;6029.643330250658;6040.275166979903;1580.9288582013837;1600.7118718630759;1605.4701948357247;1605.8073031236327;1613.3949768836687;1018.4805749068029;1041.8697662792151;1055.5408397347026;1057.6509751781894;1068.584126032994;5151.407588127413;5175.275461435402;5178.5919547409885;5199.454057324002;5224.8171314004585;5407.405185429599;5444.7822087116865;5464.371597266365;5477.7261136943;5490.423505531941;4980.153373926369;5143.27994080829;5144.408393034774;5150.4334523202415;5178.637495375363;2891.4506243813607;2903.666078401011;2915.99398207618;2916.9708302916697;2925.312551550384;5519.227308176858;5573.60984731073;5601.887962240732;5632.943670563324;5633.521150702154;5185.351831021122;5501.697406642673;5582.91625625616;5600.947483621355;5602.943970560324;5601.887962240732;5603.2435621191435;5614.24207731951;5635.520980716603;5645.1532270159805;5082.949170508396;5405.405405405324;5412.161115027177;5455.672659640434;5512.834614961557;5293.523582877559;5336.919946200887;5350.732463376893;5367.35591728995;5370.570354371719;5574.358948720786;5590.888182236414;5597.608167428241;5626.324841019104;5632.380438151718;4098.4262203292155;4154.3976123462535;4169.0411205423525;4421.137878113737;4447.6219521341045;6103.542234332354;6209.192804935329;6230.127782110599;6231.314555398993;6252.874942501034;6136.386361363828;6138.846528836815;6170.56806023592;6185.6907154641185;6196.1635179252025;5995.340512543704;6111.022236442148;6149.477294429845;6158.599691020069;6176.702892392648;6039.758409663565;6047;6048.969755151196;6074.906516827014;6087.8782424350375;6184.412480814315;6194.442500175006;6203.379662033761;6204.3795620437595;6210.161628180319;5911.019808364246;6042.093685947143;6053.184044786459;6056.395055310434;6074.210352654303 22:13:42 INFO - PROCESS | 20512 | |5;yui.html;824.2293455618997;831.4263158420686;863.1929146248257;907.0793144957867;916.657572841539;16381.918090409546;17713.291468341282;17777.11114444279;17896.19467123979;17949.820501794977;14423.63940901478;14443.350049247783;14480.2759862007;14498.057626254285;14540;852.7313896122718;883.6421249394006;884.1158841158841;884.5179377239388;885.6191837509868;867.7873920889359;880.2341962492641;896.6727144592243;897.757605446529;908.9727308180732;932.4591736792678;934.7569631895722;936.2556767369923;941.7174847545743;941.7598512379334;940.5438362394233;940.9012053734363;941.1623654947102;941.4210260689695;944.211583327922;691;691.6126968897383;692.1210063219683;693.2097408953797;693.750249910032;16318.102504362256;16426.917865410596;16463.506094817174;16472.341106355732;16543.420980265753;16148.426397956837;16569.751453728084;16590.419335323204;16737.828352015364;16873.493795186165;12805.423755931006;13040.608781736562;13365.66585835352;13396.39716212763;13697.178169309773;4155.355919832431;4185.058361868555;4235.237657221699;4235.53409124996;4266.1467706458525;88522.11477885203;89306;89533;90212;90943.54528227316;11590.246633968765;11768.352740599177;11780.352080635646;11820.527178912747;12025.398730063462;21179.258725944517;21243.36269911905;21265.36203913885;21321.573568528853;21344.35966920995;9400.482973436527;9421.670241541513;9491.905080949102;9608.471534065693;9626.711198664052;4943.456219815817;4991;5011.120378092838;5022.146235140035;5127.66670166438;4111.362738775495;4119.938200926988;4229.97885010573;4247.5540068292385;4258.42511260982;743.2159072178903;745.1914672580311;753.6269546574481;757.4773406349601;757.8825282081286;645.6190847399963;696.3176087434342;708.3235510087853;759.6163937211783;796.1799346672937;10426.84751171839;10663.306885052447;10813.513391897384;10920.50857711389;10952.069074128629;634.4607083978582;693.4521727834954;711.9608421536865;719.2412005334422;740.8703476891523;4782.473927867932;4965.602751779849;5070.974645126825;5294.54996325309;5432.483914028161;115053;115623.42188289005;120616;121902;123195.15207271898;104054;104448.47775761224;104547;104781.47609261905;108288.99999999843;90988.54505727428;92464;93586.5320673406;94707;97794.99999999857;9885.456299903575;10080.193584513074;10098.49507524606;10102.39385636864;10104.848427273744;53206.66983325448;54209.18686219789;54587;54753;55286.72356638191;76330;78972.60513697394;79291;80883;81936;12719.491220351083;13117.278549679477;13230.536931207362;13247.536336228186;13290.867091328962;13377;13701.588952331445;14142;14337.784933225801;14341.78487322712;12687.873121268669;12842.614721558368;13006.47974081064;13495.39270732839;13943.721125577229;12850.614481565568;13327.733445331232;13440;13659.658508536968;13712.314384280946;13814.930925345308;13937.58187254384;14094.718105638034;14112.576622701336;14321.713565728836;10372.844407333745;10389.532471038972;10432.217583681102;11028.0074793269;11037.668869933916;10168.28821982454;10456.947715261374;11085.55175769074;11178.27341222818;11187.72030699239 22:13:42 INFO - PROCESS | 20512 | __end_tp_report 22:13:42 INFO - PROCESS | 20512 | __start_cc_report 22:13:42 INFO - PROCESS | 20512 | _x_x_mozilla_cycle_collect,344 22:13:42 INFO - PROCESS | 20512 | __end_cc_report 22:13:42 INFO - PROCESS | 20512 | __startTimestamp1477545222815__endTimestamp 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | ------- Summary: start ------- 22:13:42 INFO - PROCESS | 20512 | Number of tests: 6 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | [#0] dojo.html Cycles:165 Average:5644.11 Median:5906.94 stddev:654.33 (11.1%) stddev-sans-first:628.09 22:13:42 INFO - PROCESS | 20512 | Values: 3220.0 3224.6 3245.9 3265.0 3268.1 5692.0 5769.4 5772.1 5775.3 5805.6 5592.3 5684.3 5708.5 5709.6 5718.2 5142.8 5164.2 5165.0 5207.4 5215.1 5159.8 5160.4 5161.2 5220.8 5273.2 5022.9 5110.5 5120.1 5128.0 5175.5 5079.5 5204.3 5236.1 5251.3 5270.9 5154.3 5182.6 5197.6 5202.5 5208.8 5422.4 5484.8 5598.7 5686.4 5758.5 5944.0 5988.3 5994.8 6052.5 6086.7 6017.8 6018.6 6030.2 6036.3 6077.1 5505.4 5546.8 5570.4 5628.6 5651.5 6099.6 6175.8 6186.0 6188.9 6191.0 5917.6 5955.7 5966.2 5974.9 5989.8 5802.8 5971.4 6020.0 6044.2 6057.1 6032.3 6109.3 6134.4 6147.1 6157.0 6107.5 6118.0 6119.4 6124.2 6153.8 4508.6 4535.1 4700.9 4705.6 4718.1 4002.8 4059.0 4078.2 4083.4 4094.9 5633.8 5668.5 5671.4 5684.1 5704.4 5914.0 5921.5 5957.7 5964.1 5965.6 5721.8 5737.4 5781.6 5784.0 5797.0 5231.2 5262.2 5313.5 5328.0 5335.0 6136.1 6188.5 6227.7 6293.3 6392.9 6167.5 6176.9 6189.0 6193.4 6218.2 6339.9 6356.4 6379.2 6403.2 6403.4 5826.5 5864.7 5906.9 6044.2 6063.2 6092.9 6136.1 6138.6 6141.6 6151.7 5974.6 5986.1 6003.0 6012.5 6018.6 5786.1 5946.5 5962.4 5982.5 6011.8 5983.4 6003.6 6003.9 6005.3 6015.9 6048.4 6049.6 6086.1 6100.2 6103.1 5678.6 5792.0 5818.7 5948.3 5951.3 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | [#1] ext.html Cycles:180 Average:68820.97 Median:24624.87 stddev:141889.84 (576.2%) stddev-sans-first:142195.39 22:13:42 INFO - PROCESS | 20512 | Values: 586.0 634.3 649.7 667.7 708.0 40355.6 45045.8 45123.3 45141.3 45218.3 46012.3 46471.5 46753.8 46895.8 46995.8 8856.3 8934.6 8940.2 8950.5 9034.1 8914.8 8947.8 8973.2 9009.0 9027.9 8990.7 8993.4 8996.1 9048.6 9092.0 8961.3 9072.3 9079.5 9102.7 9128.8 9000.0 9036.4 9052.0 9056.3 9079.7 43181.6 43627.6 43685.0 43732.0 43801.1 3822.4 3834.3 3948.7 3993.5 4207.4 34224.5 34227.3 34523.5 36373.3 36449.5 4395.6 4487.0 4502.9 4608.9 4609.5 375021.1 376939.0 377458.0 378112.0 378471.0 58378.7 58406.4 58411.1 58495.4 58554.4 39222.2 40229.4 40388.2 65405.5 65644.0 19066.2 19106.3 19177.1 19279.0 19316.6 14775.3 14815.6 14848.6 14878.7 15034.2 20671.0 20691.1 20911.2 20953.8 21049.3 7538.4 7571.7 7573.8 7626.3 7693.2 1050.7 1062.3 1108.2 1117.5 1155.7 34002.1 34212.8 34218.8 34415.5 34811.8 1158.1 1168.1 1172.7 1190.9 1207.6 17476.7 17769.6 17854.3 17971.6 18001.5 634017.0 637538.0 648200.0 649238.0 649784.8 64166.4 64691.7 64885.7 64972.4 65088.3 1384.5 1426.8 1441.9 1463.7 1466.7 32237.8 33571.3 33688.7 34107.7 34275.7 54908.0 58571.4 60248.4 60879.1 65994.0 535705.0 545069.0 545083.0 546413.0 546555.0 47332.3 47592.3 48161.0 48330.3 48528.0 51366.0 51922.7 52460.7 53496.0 53510.2 43754.0 43942.3 44745.0 44762.8 44901.8 43045.8 44379.6 44514.3 44723.3 45591.3 43489.0 43975.6 44569.0 44927.0 47922.0 38402.2 38883.7 39162.2 39991.4 40031.0 29570.7 29709.0 29767.6 30283.7 30344.5 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | [#2] jquery.html Cycles:180 Average:29690.40 Median:12761.84 stddev:82816.79 (648.9%) stddev-sans-first:83019.95 22:13:42 INFO - PROCESS | 20512 | Values: 419.3 422.5 432.9 435.7 435.9 18479.1 19580.6 20273.6 20362.6 20434.1 19361.2 19455.0 19719.2 19789.9 19963.7 5718.9 5960.4 5961.3 5966.6 5975.8 5855.0 5860.0 5862.1 5952.5 5970.6 5576.9 5851.6 5865.5 5875.9 5877.1 5783.2 5796.0 5808.4 5812.1 5826.5 5207.1 5219.9 5222.9 5226.3 5234.0 22674.9 23208.9 23309.7 23514.8 23606.9 2314.8 2400.2 2536.5 2580.2 2602.5 10883.8 10894.0 11085.5 12849.8 13817.1 1974.4 1980.6 1984.2 2016.4 2072.7 83006.6 83228.6 83462.0 83742.0 84130.0 11404.5 11523.7 11590.5 11630.5 11838.8 16211.4 16322.3 16373.4 16400.4 16409.8 8731.5 8765.9 8823.4 8962.1 9106.6 8715.7 8735.0 8747.2 8881.6 8969.9 4979.1 5005.2 5037.0 5068.8 5097.6 2082.4 2098.0 2107.4 2132.4 2156.3 985.3 1009.5 1024.2 1053.9 1112.6 19191.0 19714.4 19822.7 19972.3 20148.6 1044.2 1059.4 1076.1 1082.0 1093.6 10337.2 11065.1 11093.8 11139.4 11203.7 488671.0 490658.0 498491.0 503755.0 531030.0 88283.6 90495.1 92186.0 92762.7 95614.0 1025.2 1031.2 1038.3 1051.3 1058.6 16660.3 17141.1 17542.3 17549.9 17721.4 41015.8 41418.4 41924.8 42765.0 43293.4 57538.7 57746.7 57902.0 58125.4 59368.7 8957.9 9008.3 9121.8 9203.1 9257.6 10418.8 13390.9 14168.8 14206.0 14355.9 11665.2 11690.1 11817.4 11868.3 12002.0 12010.9 12011.2 12048.2 12070.6 12221.3 11512.7 11617.8 11638.8 11722.5 12115.5 8781.6 8848.2 8896.0 8976.7 9346.4 10317.8 11465.6 11813.9 11977.7 12035.8 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | [#3] mootools.html Cycles:180 Average:6399.60 Median:4709.65 stddev:3748.27 (79.6%) stddev-sans-first:3741.14 22:13:42 INFO - PROCESS | 20512 | Values: 1559.7 1561.5 1730.6 1801.4 1859.4 8573.3 9045.8 9208.7 9947.6 9971.5 9115.0 9440.9 9449.4 9452.9 9459.6 2490.1 2517.7 2540.7 2545.7 2546.0 2433.6 2548.5 2549.6 2551.7 2553.2 2521.2 2526.0 2529.0 2534.5 2544.4 2441.2 2463.8 2466.0 2471.7 2473.1 2421.3 2431.5 2434.7 2442.5 2478.6 9788.1 9841.4 9881.5 9897.1 9939.6 5490.8 5548.4 5604.7 5622.9 5623.4 3292.1 3366.6 3372.9 3437.6 3456.1 1009.5 1012.4 1031.4 1063.8 1067.5 13801.3 13831.2 13856.3 13864.3 13889.9 4679.6 4709.6 4857.2 4910.0 4990.1 11557.0 11561.1 11571.2 11586.6 11621.5 5635.9 5684.5 5684.8 5718.2 5736.5 4524.5 5120.3 5194.1 5201.7 5205.2 4246.3 4288.7 4296.0 4306.8 4335.3 1324.2 1325.1 1337.6 1370.5 1382.9 977.9 1074.5 1075.1 1077.3 1096.0 6519.6 6556.8 6726.5 6750.4 6767.0 468.9 470.5 480.9 482.3 482.5 4572.9 4654.6 4709.7 4742.3 4759.9 11770.6 12294.7 12311.3 12316.6 12437.9 11959.9 12172.1 12193.8 12199.6 12237.2 9058.4 9181.2 9189.5 9194.6 9242.6 6681.3 6822.2 6971.5 7033.9 7097.8 11160.1 11278.1 11317.8 11410.1 11441.3 11388.5 11417.5 11514.5 11524.8 11552.0 7816.0 8343.3 8516.0 8710.1 8879.2 9492.7 9597.6 9633.4 9634.4 9653.8 8520.1 8698.4 8786.9 8806.6 8881.6 8594.7 8654.5 8660.2 8761.5 8826.3 7428.2 7599.7 7601.9 7631.0 7637.1 5760.8 5824.0 5949.6 5968.7 6022.0 5717.6 5774.0 5786.0 5840.7 5915.2 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | [#4] prototype.html Cycles:180 Average:4916.76 Median:5368.96 stddev:1405.94 (26.2%) stddev-sans-first:1367.24 22:13:42 INFO - PROCESS | 20512 | Values: 338.4 348.3 352.5 356.5 360.1 5579.4 5726.4 5800.6 5807.7 5814.9 5658.7 5702.2 5706.7 5713.7 5713.9 4475.0 4491.5 4524.2 4524.9 4528.4 4380.1 4451.5 4490.1 4491.3 4500.0 4443.8 4471.8 4476.3 4476.5 4480.9 4471.9 4474.4 4478.2 4487.6 4488.1 3826.9 3970.2 4009.4 4011.5 4019.5 5543.7 5553.7 5562.9 5563.9 5578.2 5226.8 5237.4 5237.6 5241.1 5256.9 5888.9 5939.7 5969.2 6019.9 6046.4 4455.8 4458.4 4466.2 4468.6 4478.4 6238.4 6242.0 6260.4 6260.8 6283.5 4415.4 4564.2 4587.1 4590.8 4602.9 4663.1 4673.3 4673.9 4678.3 4680.6 5241.3 5246.9 5262.4 5268.2 5274.6 5835.8 5886.6 5971.3 6029.6 6040.3 1580.9 1600.7 1605.5 1605.8 1613.4 1018.5 1041.9 1055.5 1057.7 1068.6 5151.4 5175.3 5178.6 5199.5 5224.8 5407.4 5444.8 5464.4 5477.7 5490.4 4980.2 5143.3 5144.4 5150.4 5178.6 2891.5 2903.7 2916.0 2917.0 2925.3 5519.2 5573.6 5601.9 5632.9 5633.5 5185.4 5501.7 5582.9 5600.9 5602.9 5601.9 5603.2 5614.2 5635.5 5645.2 5082.9 5405.4 5412.2 5455.7 5512.8 5293.5 5336.9 5350.7 5367.4 5370.6 5574.4 5590.9 5597.6 5626.3 5632.4 4098.4 4154.4 4169.0 4421.1 4447.6 6103.5 6209.2 6230.1 6231.3 6252.9 6136.4 6138.8 6170.6 6185.7 6196.2 5995.3 6111.0 6149.5 6158.6 6176.7 6039.8 6047.0 6049.0 6074.9 6087.9 6184.4 6194.4 6203.4 6204.4 6210.2 5911.0 6042.1 6053.2 6056.4 6074.2 22:13:42 INFO - PROCESS | 20512 | 22:13:42 INFO - PROCESS | 20512 | [#5] yui.html Cycles:180 Average:22189.96 Median:4137.65 stddev:32200.85 (778.2%) stddev-sans-first:32251.22 22:13:42 INFO - PROCESS | 20512 | Values: 824.2 831.4 863.2 907.1 916.7 16381.9 17713.3 17777.1 17896.2 17949.8 14423.6 14443.4 14480.3 14498.1 14540.0 852.7 883.6 884.1 884.5 885.6 867.8 880.2 896.7 897.8 909.0 932.5 934.8 936.3 941.7 941.8 940.5 940.9 941.2 941.4 944.2 691.0 691.6 692.1 693.2 693.8 16318.1 16426.9 16463.5 16472.3 16543.4 16148.4 16569.8 16590.4 16737.8 16873.5 12805.4 13040.6 13365.7 13396.4 13697.2 4155.4 4185.1 4235.2 4235.5 4266.1 88522.1 89306.0 89533.0 90212.0 90943.5 11590.2 11768.4 11780.4 11820.5 12025.4 21179.3 21243.4 21265.4 21321.6 21344.4 9400.5 9421.7 9491.9 9608.5 9626.7 4943.5 4991.0 5011.1 5022.1 5127.7 4111.4 4119.9 4230.0 4247.6 4258.4 743.2 745.2 753.6 757.5 757.9 645.6 696.3 708.3 759.6 796.2 10426.8 10663.3 10813.5 10920.5 10952.1 634.5 693.5 712.0 719.2 740.9 4782.5 4965.6 5071.0 5294.5 5432.5 115053.0 115623.4 120616.0 121902.0 123195.2 104054.0 104448.5 104547.0 104781.5 108289.0 90988.5 92464.0 93586.5 94707.0 97795.0 9885.5 10080.2 10098.5 10102.4 10104.8 53206.7 54209.2 54587.0 54753.0 55286.7 76330.0 78972.6 79291.0 80883.0 81936.0 12719.5 13117.3 13230.5 13247.5 13290.9 13377.0 13701.6 14142.0 14337.8 14341.8 12687.9 12842.6 13006.5 13495.4 13943.7 12850.6 13327.7 13440.0 13659.7 13712.3 13814.9 13937.6 14094.7 14112.6 14321.7 10372.8 10389.5 10432.2 11028.0 11037.7 10168.3 10456.9 11085.6 11178.3 11187.7 22:13:42 INFO - PROCESS | 20512 | -------- Summary: end -------- 22:13:42 INFO - PROCESS | 20512 | 22:13:43 INFO - TEST-INFO | 20512: exit 0 22:13:43 INFO - TEST-OK | dromaeo_css | took 1109602ms 22:13:43 INFO - TEST-START | kraken 22:13:43 INFO - operating with platform_type : linux_ 22:13:43 INFO - Initialising browser for kraken test... 22:13:43 INFO - TEST-INFO | started process 20752 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPBfrs8/profile http://localhost:46455/getInfo.html) 22:13:47 INFO - PROCESS | 20752 | __metrics Screen width/height:1600/1200 22:13:47 INFO - PROCESS | 20752 | colorDepth:24 22:13:47 INFO - PROCESS | 20752 | Browser inner width/height: 1024/697 22:13:47 INFO - PROCESS | 20752 | __metrics 22:13:48 ERROR - PROCESS | 20752 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:13:48 INFO - TEST-INFO | 20752: exit 0 22:13:48 INFO - Browser initialized. 22:13:48 INFO - Running cycle 1/1 for kraken test... 22:13:48 INFO - TEST-INFO | started process 20877 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPBfrs8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:00 INFO - PROCESS | 20877 | RSS: Main: 150491136 22:14:00 INFO - PROCESS | 20877 | 22:14:03 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46455/tests/kraken/driver.html?testName=audio-beat-detection) 22:14:03 INFO - PROCESS | 20877 | RSS: Main: 157335552 22:14:03 INFO - PROCESS | 20877 | 22:14:06 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46455/tests/kraken/driver.html?testName=audio-dft) 22:14:07 INFO - PROCESS | 20877 | RSS: Main: 155488256 22:14:07 INFO - PROCESS | 20877 | 22:14:09 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46455/tests/kraken/driver.html?testName=audio-fft) 22:14:10 INFO - PROCESS | 20877 | RSS: Main: 156332032 22:14:10 INFO - PROCESS | 20877 | 22:14:12 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46455/tests/kraken/driver.html?testName=audio-oscillator) 22:14:13 INFO - PROCESS | 20877 | RSS: Main: 156499968 22:14:13 INFO - PROCESS | 20877 | 22:14:15 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46455/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:14:16 INFO - PROCESS | 20877 | RSS: Main: 155619328 22:14:16 INFO - PROCESS | 20877 | 22:14:19 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46455/tests/kraken/driver.html?testName=imaging-darkroom) 22:14:19 INFO - PROCESS | 20877 | RSS: Main: 156274688 22:14:19 INFO - PROCESS | 20877 | 22:14:23 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46455/tests/kraken/driver.html?testName=imaging-desaturate) 22:14:23 INFO - PROCESS | 20877 | RSS: Main: 156332032 22:14:23 INFO - PROCESS | 20877 | 22:14:26 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46455/tests/kraken/driver.html?testName=json-parse-financial) 22:14:27 INFO - PROCESS | 20877 | RSS: Main: 155869184 22:14:27 INFO - PROCESS | 20877 | 22:14:28 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46455/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:14:29 INFO - PROCESS | 20877 | RSS: Main: 155049984 22:14:29 INFO - PROCESS | 20877 | 22:14:31 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:14:31 INFO - PROCESS | 20877 | RSS: Main: 156549120 22:14:31 INFO - PROCESS | 20877 | 22:14:33 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:14:34 INFO - PROCESS | 20877 | RSS: Main: 156123136 22:14:34 INFO - PROCESS | 20877 | 22:14:36 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:14:37 INFO - PROCESS | 20877 | RSS: Main: 156381184 22:14:37 INFO - PROCESS | 20877 | 22:14:40 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:14:41 INFO - PROCESS | 20877 | RSS: Main: 157679616 22:14:41 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | Cycle 1(1): loaded http://localhost:46455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46455/tests/kraken/driver.html?testName=ai-astar) 22:14:42 INFO - PROCESS | 20877 | RSS: Main: 159305728 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | __start_tp_report 22:14:42 INFO - PROCESS | 20877 | _x_x_mozilla_page_load 22:14:42 INFO - PROCESS | 20877 | _x_x_mozilla_page_load_details 22:14:42 INFO - PROCESS | 20877 | |i|pagename|runs| 22:14:42 INFO - PROCESS | 20877 | |0;ai-astar;102;113;106;112;113;114;114;112;111;111 22:14:42 INFO - PROCESS | 20877 | |1;audio-beat-detection;145;116;140;178;119;140;145;119;173;119 22:14:42 INFO - PROCESS | 20877 | |2;audio-dft;163;164;163;162;163;165;161;161;162;162 22:14:42 INFO - PROCESS | 20877 | |3;audio-fft;78;77;77;182;77;85;76;76;179;77 22:14:42 INFO - PROCESS | 20877 | |4;audio-oscillator;84;84;157;83;84;83;139;85;86;151 22:14:42 INFO - PROCESS | 20877 | |5;imaging-gaussian-blur;117;118;118;117;118;119;119;117;118;118 22:14:42 INFO - PROCESS | 20877 | |6;imaging-darkroom;148;148;148;147;148;147;147;147;147;148 22:14:42 INFO - PROCESS | 20877 | |7;imaging-desaturate;96;96;97;97;96;95;95;99;97;96 22:14:42 INFO - PROCESS | 20877 | |8;json-parse-financial;50;56;51;50;50;51;51;50;52;54 22:14:42 INFO - PROCESS | 20877 | |9;json-stringify-tinderbox;61;61;60;60;63;61;61;61;61;63 22:14:42 INFO - PROCESS | 20877 | |10;stanford-crypto-aes;75;79;75;77;79;74;72;74;79;83 22:14:42 INFO - PROCESS | 20877 | |11;stanford-crypto-ccm;119;129;141;125;128;119;131;119;158;137 22:14:42 INFO - PROCESS | 20877 | |12;stanford-crypto-pbkdf2;153;153;158;165;151;154;150;157;155;162 22:14:42 INFO - PROCESS | 20877 | |13;stanford-crypto-sha256-iterative;62;62;63;62;61;65;61;63;60;63 22:14:42 INFO - PROCESS | 20877 | __end_tp_report 22:14:42 INFO - PROCESS | 20877 | __start_cc_report 22:14:42 INFO - PROCESS | 20877 | _x_x_mozilla_cycle_collect,848 22:14:42 INFO - PROCESS | 20877 | __end_cc_report 22:14:42 INFO - PROCESS | 20877 | __startTimestamp1477545282903__endTimestamp 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | ------- Summary: start ------- 22:14:42 INFO - PROCESS | 20877 | Number of tests: 14 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:3.85 (3.4%) stddev-sans-first:2.44 22:14:42 INFO - PROCESS | 20877 | Values: 102.0 113.0 106.0 112.0 113.0 114.0 114.0 112.0 111.0 111.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:142.50 stddev:22.31 (15.7%) stddev-sans-first:23.57 22:14:42 INFO - PROCESS | 20877 | Values: 145.0 116.0 140.0 178.0 119.0 140.0 145.0 119.0 173.0 119.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#2] audio-dft Cycles:10 Average:162.60 Median:163.00 stddev:1.26 (0.8%) stddev-sans-first:1.33 22:14:42 INFO - PROCESS | 20877 | Values: 163.0 164.0 163.0 162.0 163.0 165.0 161.0 161.0 162.0 162.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#3] audio-fft Cycles:10 Average:98.40 Median:77.00 stddev:43.35 (56.3%) stddev-sans-first:45.35 22:14:42 INFO - PROCESS | 20877 | Values: 78.0 77.0 77.0 182.0 77.0 85.0 76.0 76.0 179.0 77.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:84.00 stddev:31.64 (37.7%) stddev-sans-first:32.75 22:14:42 INFO - PROCESS | 20877 | Values: 84.0 84.0 157.0 83.0 84.0 83.0 139.0 85.0 86.0 151.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 22:14:42 INFO - PROCESS | 20877 | Values: 117.0 118.0 118.0 117.0 118.0 119.0 119.0 117.0 118.0 118.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 22:14:42 INFO - PROCESS | 20877 | Values: 148.0 148.0 148.0 147.0 148.0 147.0 147.0 147.0 147.0 148.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:1.17 (1.2%) stddev-sans-first:1.24 22:14:42 INFO - PROCESS | 20877 | Values: 96.0 96.0 97.0 97.0 96.0 95.0 95.0 99.0 97.0 96.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.00 stddev:2.01 (3.9%) stddev-sans-first:2.06 22:14:42 INFO - PROCESS | 20877 | Values: 50.0 56.0 51.0 50.0 50.0 51.0 51.0 50.0 52.0 54.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:1.03 (1.7%) stddev-sans-first:1.09 22:14:42 INFO - PROCESS | 20877 | Values: 61.0 61.0 60.0 60.0 63.0 61.0 61.0 61.0 61.0 63.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:78.00 stddev:3.30 (4.2%) stddev-sans-first:3.44 22:14:42 INFO - PROCESS | 20877 | Values: 75.0 79.0 75.0 77.0 79.0 74.0 72.0 74.0 79.0 83.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#11] stanford-crypto-ccm Cycles:10 Average:130.60 Median:130.00 stddev:12.22 (9.4%) stddev-sans-first:12.22 22:14:42 INFO - PROCESS | 20877 | Values: 119.0 129.0 141.0 125.0 128.0 119.0 131.0 119.0 158.0 137.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:156.00 stddev:4.78 (3.1%) stddev-sans-first:4.96 22:14:42 INFO - PROCESS | 20877 | Values: 153.0 153.0 158.0 165.0 151.0 154.0 150.0 157.0 155.0 162.0 22:14:42 INFO - PROCESS | 20877 | 22:14:42 INFO - PROCESS | 20877 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.50 stddev:1.40 (2.2%) stddev-sans-first:1.48 22:14:42 INFO - PROCESS | 20877 | Values: 62.0 62.0 63.0 62.0 61.0 65.0 61.0 63.0 60.0 63.0 22:14:42 INFO - PROCESS | 20877 | -------- Summary: end -------- 22:14:42 INFO - PROCESS | 20877 | 22:14:43 INFO - TEST-INFO | 20877: exit 0 22:14:43 INFO - TEST-OK | kraken | took 60341ms 22:14:43 INFO - SUITE-END | took 1169s 22:14:44 INFO - Completed test suite (00:19:30) 22:14:44 INFO - javascript benchmark 22:14:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7700.250665448162, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3219.967800321997, 3224.6452890182077, 3245.8539365728557, 3264.9836750816244, 3268.1176082457737, 5691.971540142299, 5769.423057694228, 5772.105323674838, 5775.3358363788075, 5805.593608447411, 5592.328920529532, 5684.317881854184, 5708.486236238738, 5709.628874123179, 5718.228039214705, 5142.845714628567, 5164.225366195082, 5165.04446677364, 5207.375114986188, 5215.1395019821875, 5159.793608255665, 5160.432352441228, 5161.200013997836, 5220.791168353261, 5273.209018647195, 5022.874428139307, 5110.540051395374, 5120.078385890538, 5128.025675121715, 5175.534201921826, 5079.542841144296, 5204.34945631796, 5236.083685355047, 5251.317328747276, 5270.894582108374, 5154.304168937183, 5182.559482443997, 5197.58419326453, 5202.479752024768, 5208.8437346879655, 5422.43064478232, 5484.7531861065945, 5598.720063996825, 5686.431356864322, 5758.481736643722, 5944.048952167675, 5988.341282458926, 5994.760209591611, 6052.515798736091, 6086.665233412161, 6017.819465416044, 6018.578699491038, 6030.18592490017, 6036.3360030396625, 6077.088436734481, 5505.366882808488, 5546.805861794898, 5570.41510641385, 5628.577856660726, 5651.547876169938, 6099.573029887954, 6175.752969881154, 6185.969070154621, 6188.907166392508, 6191.0, 5917.644941303535, 5955.702214889238, 5966.164736936832, 5974.940250597525, 5989.8203053908455, 5802.82591522255, 5971.4028597140805, 6020.0, 6044.214252147281, 6057.091436284592, 6032.276126864805, 6109.313572000111, 6134.40597271149, 6147.0779383092895, 6157.014877619559, 6107.463072948034, 6117.957087457094, 6119.350229749377, 6124.229237387393, 6153.753849846045, 4508.639308855284, 4535.138323718483, 4700.929486057712, 4705.55297246761, 4718.127146477913, 4002.839886404511, 4058.9594104059174, 4078.225137223918, 4083.407905853694, 4094.8566800161852, 5633.802816901471, 5668.461496157858, 5671.404502527258, 5684.118961560965, 5704.429557044263, 5913.970430147908, 5921.526277897844, 5957.672328022, 5964.135200395918, 5965.612235204698, 5721.77112915487, 5737.3975732548315, 5781.566382521328, 5783.971080144573, 5797.0, 5231.215317702299, 5262.184361423985, 5313.495217954288, 5327.9733601331745, 5334.973325133349, 6136.12084543884, 6188.535859810442, 6227.719752611053, 6293.2762732285455, 6392.936070639327, 6167.537434692326, 6176.907346389898, 6188.9690551546955, 6193.38066193377, 6218.160548325918, 6339.90490142639, 6356.4279214870885, 6379.234491861006, 6403.199600049994, 6403.391677790602, 5826.533877289722, 5864.736086876186, 5906.940930590639, 6044.15381846533, 6063.151158837854, 6092.908606370819, 6136.110264011604, 6138.631682099089, 6141.631502109888, 6151.6924153793025, 5974.61165024272, 5986.102084687332, 6002.969985150221, 6012.549058820693, 6018.578699490994, 5786.07422812348, 5946.464818166386, 5962.373950735284, 5982.46157845796, 6011.789587364421, 5983.401659833982, 6003.6397816131175, 6003.939960600513, 6005.339412664691, 6015.909761353671, 6048.395160484092, 6049.637021778707, 6086.056661217518, 6100.176476175837, 6103.084537319438, 5678.602497825113, 5792.0, 5818.679972601378, 5948.345681974893, 5951.28584569837], "value": 5598.688036255414, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.035970827988, 634.2769243062908, 649.7076315657954, 667.7095463473389, 707.994647600408, 40355.59644403555, 45045.77477112614, 45123.32315015273, 45141.322880156826, 45218.32172517415, 46012.30981535271, 46471.53528464723, 46753.766231168884, 46895.765521172434, 46995.76502117485, 8856.335774816866, 8934.597943092544, 8940.15068568485, 8950.507722075283, 9034.05142460042, 8914.821703565925, 8947.77630559238, 8973.192412682858, 9008.954955225216, 9027.864582031274, 8990.730278091667, 8993.415427997159, 8996.145366190201, 9048.597467392532, 9092.0, 8961.297353502814, 9072.27421806254, 9079.546022698838, 9102.681406150752, 9128.817423651557, 8999.955000225022, 9036.412633178888, 9051.954740226323, 9056.320775941766, 9079.727608171765, 43181.56818431807, 43627.56372436298, 43685.0, 43732.0, 43801.12397752027, 3822.3502004659276, 3834.2906562286066, 3948.74333168346, 3993.5008123984503, 4207.389928460387, 34224.48663270053, 34227.31545369079, 34523.482147767805, 36373.27253454916, 36449.453258201145, 4395.582419670126, 4486.967997360625, 4502.909941801178, 4608.884777880547, 4609.516000819899, 375021.1248943765, 376939.0, 377458.0, 378112.0, 378471.0, 58378.70810646004, 58406.4159358401, 58411.123833142534, 58495.415045849, 58554.414455855745, 39222.21555568872, 40229.39655905164, 40388.19223615511, 65405.4972853526, 65644.0, 19066.2373505061, 19106.33127840519, 19177.137028833735, 19279.036048197533, 19316.613667726568, 14775.335109920079, 14815.555533334018, 14848.628784280263, 14878.702425951637, 15034.173120478259, 20671.0, 20691.137797598287, 20911.163553457995, 20953.790462095185, 21049.263275785273, 7538.359239464707, 7571.659275332623, 7573.810654733566, 7626.313631773056, 7693.153753087156, 1050.6637875879799, 1062.293574772783, 1108.2353176308482, 1117.452448300327, 1155.7284038251005, 34002.14994625105, 34212.82893585516, 34218.82890585581, 34415.483767743506, 34811.825940870134, 1158.0677554568654, 1168.1122347016328, 1172.6540670502052, 1190.880911908802, 1207.643745095199, 17476.650466990588, 17769.555761106076, 17854.28582856697, 17971.640567188584, 18001.54996125081, 634017.0, 637538.0, 648200.0, 649238.0, 649784.7510762416, 64166.35833641604, 64691.67654161793, 64885.67557162184, 64972.35027649758, 65088.34911650823, 1384.4808196926153, 1426.764583843675, 1441.92069436182, 1463.6926245488567, 1466.6846627974824, 32237.838810806734, 33571.32857342888, 33688.66311336855, 34107.65892341045, 34275.657243427246, 54908.0, 58571.4142858583, 60248.39751602428, 60879.086813696944, 65994.0, 535705.0, 545069.0, 545083.0, 546413.0, 546555.0, 47332.290015650484, 47592.28611570899, 48161.0, 48330.275045873634, 48528.0, 51366.0, 51922.740386297824, 52460.73769631128, 53496.0, 53510.197347039044, 43754.0, 43942.34086488641, 44745.0, 44762.77618611886, 44901.77549112234, 43045.7847710772, 44379.55620443754, 44514.3322850151, 44723.32915006342, 45591.31613025874, 43489.0, 43975.560244398424, 44569.0, 44927.0, 47922.0, 38402.23195536018, 38883.66697998129, 39162.2167556653, 39991.40012899867, 40031.0, 29570.704292957656, 29709.0, 29767.55348669815, 30283.697163028086, 30344.54483182798], "value": 21790.231642953244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.3479139937396, 422.46769070970566, 432.88312155717955, 435.7472665853806, 435.9280718681418, 18479.076046197686, 19580.608387832253, 20273.59452810943, 20362.592748145027, 20434.08046637901, 19361.225550977942, 19455.0, 19719.211231550722, 19789.901050494762, 19963.700544491843, 5718.857028574277, 5960.374160713128, 5961.344252132262, 5966.582339236255, 5975.820725378224, 5854.970725146369, 5859.970700146515, 5862.120681897707, 5952.523798096142, 5970.552208584352, 5576.86057848553, 5851.590388672795, 5865.472107510323, 5875.853103672421, 5877.089051197071, 5783.190353350513, 5796.0, 5808.4481974212595, 5812.099124635688, 5826.504747096459, 5207.062728708831, 5219.921701174486, 5222.947770522284, 5226.320578324793, 5234.0, 22674.886625566767, 23208.883955580113, 23309.650355244685, 23514.7648523516, 23606.881965590233, 2314.7916687498205, 2400.23192578375, 2536.4927014597156, 2580.2259322203454, 2602.4534847682007, 10883.83674244887, 10894.0, 11085.50115244816, 12849.807252891214, 13817.101888377223, 1974.3879397386713, 1980.6434841728483, 1984.1765667248076, 2016.41523958052, 2072.7409073865765, 83006.58496707599, 83228.58385708033, 83462.0, 83742.0, 84130.0, 11404.486798094105, 11523.71190720222, 11590.478428470571, 11630.476628551736, 11838.822417663741, 16211.432599858948, 16322.347106115622, 16373.42693005739, 16400.425985090464, 16409.753853692204, 8731.476111433334, 8765.868511972325, 8823.382363234512, 8962.103789621116, 9106.5884787858, 8715.738527844049, 8734.95632521846, 8747.212750852326, 8881.555922203994, 8969.910300897038, 4979.078870408914, 5005.174146265826, 5037.0, 5068.847934561969, 5097.617678674114, 2082.4065141434617, 2097.979020209809, 2107.3888572314163, 2132.424245453719, 2156.3207589609224, 985.2905907746411, 1009.4750729620556, 1024.206240163864, 1053.910417614496, 1112.593903225317, 19191.0, 19714.40856774299, 19822.70265946012, 19972.300969466, 20148.597028059357, 1044.2429238801808, 1059.390850261103, 1076.0853274716585, 1082.0207712020633, 1093.578972095749, 10337.173026157889, 11065.114790816715, 11093.778124437627, 11139.387333696563, 11203.719907002227, 488671.0, 490658.0, 498491.0, 503755.0, 531030.0, 88283.55858220668, 90495.09504904867, 92186.0, 92762.68093297722, 95614.0, 1025.195221750922, 1031.1750599520353, 1038.314712289885, 1051.311391038886, 1058.5871510111062, 16660.250288736792, 17141.142942853057, 17542.298308067533, 17549.912250438665, 17721.379751708628, 41015.794921025205, 41418.37872431856, 41924.790376047924, 42765.0, 43293.3505997404, 57538.7123064382, 57746.711266443395, 57902.0, 58125.418745813695, 59368.70315648394, 8957.865632015395, 9008.324375671982, 9121.771955700895, 9203.079692030744, 9257.583408746488, 10418.843717344094, 13390.866091339352, 14168.787468187778, 14206.0, 14355.856441435451, 11665.183437159656, 11690.123240756808, 11817.409129543317, 11868.347240901488, 12002.0, 12010.939945300217, 12011.219270747373, 12048.216865903689, 12070.637880863587, 12221.327826969602, 11512.712182195512, 11617.76764464689, 11638.825417618911, 11722.472488738198, 12115.515379384726, 8781.604827782892, 8848.20366167048, 8896.021437641984, 8976.685815996409, 9346.439213647203, 10317.793644127225, 11465.598704045318, 11813.881861181622, 11977.700557486132, 12035.819462708241], "value": 9554.094780365023, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1559.6568754873927, 1561.4847100456848, 1730.5846596816768, 1801.3875282403988, 1859.4421673497948, 8573.271271941892, 9045.773855653595, 9208.67769628063, 9947.602095916163, 9971.451570163637, 9115.0, 9440.858387124199, 9449.38578992365, 9452.905470945288, 9459.621615135404, 2490.1035627174156, 2517.697876254852, 2540.6697129373156, 2545.6563363945907, 2546.019782383777, 2433.6227884677905, 2548.5157820014138, 2549.6430499730054, 2551.719310875802, 2553.1702196786027, 2521.205820166651, 2526.027479420427, 2529.0263248649217, 2534.518441496119, 2544.402065514612, 2441.1944058460667, 2463.8275320727557, 2466.0259197616842, 2471.6910386201725, 2473.1220416751976, 2421.346236516131, 2431.538007778516, 2434.720007199177, 2442.499287646046, 2478.603423452257, 9788.070133337322, 9841.360311579725, 9881.456519891402, 9897.059779320953, 9939.602415903355, 5490.80782172622, 5548.361938377057, 5604.747786349624, 5622.943770562283, 5623.409541998072, 3292.1275861896615, 3366.5960084789735, 3372.9325413491592, 3437.553118094632, 3456.1359660084977, 1009.4548943570463, 1012.4178597306504, 1031.381171297223, 1063.8138325792954, 1067.4822710985165, 13801.309934503335, 13831.170129792145, 13856.307184640727, 13864.306784660726, 13889.861101389059, 4679.555442232982, 4709.62323014158, 4857.174280372275, 4909.95090049095, 4990.051890140861, 11557.0, 11561.132915031238, 11571.19001669875, 11586.594469193538, 11621.53513859453, 5635.915461268084, 5684.516816070681, 5684.7777727788725, 5718.170865224519, 5736.4550367715, 4524.547545245449, 5120.308758317654, 5194.0650682876685, 5201.687898726089, 5205.167173252262, 4246.320588705792, 4288.742675439484, 4296.0, 4306.763128027988, 4335.328024156261, 1324.179009014398, 1325.0856908733135, 1337.5786627212397, 1370.5408688089403, 1382.910110842792, 977.8582105594649, 1074.468138271545, 1075.0808059109506, 1077.3212875888141, 1096.0464395975553, 6519.576227545194, 6556.8360790980605, 6726.461883049345, 6750.35871592198, 6767.0, 468.920283551797, 470.5226979950563, 480.9206480930677, 482.3488290807452, 482.45723560993883, 4572.862814115582, 4654.604358629486, 4709.670323077352, 4742.264948932921, 4759.904801903943, 11770.646880593597, 12294.692632684075, 12311.261324320569, 12316.630501084981, 12437.93781031089, 11959.880401195876, 12172.087093467848, 12193.756124877631, 12199.634010979686, 12237.20458170216, 9058.411203271899, 9181.173694367539, 9189.540522973824, 9194.586243619053, 9242.584083716249, 6681.265060843206, 6822.215445223665, 6971.5468494547695, 7033.859322813412, 7097.787066388017, 11160.10719142483, 11278.09775217997, 11317.830232546354, 11410.14423918193, 11441.313521188755, 11388.487518061873, 11417.486213120595, 11514.481848316678, 11524.82712759326, 11551.960323570918, 7816.0, 8343.332533397435, 8515.957420212859, 8710.12898710124, 8879.200871921557, 9492.667756628485, 9597.568109435231, 9633.373830700983, 9634.421934683942, 9653.806923861624, 8520.10538893432, 8698.391112622056, 8786.912130878609, 8806.634971579415, 8881.644734210558, 8594.742157735278, 8654.48073115615, 8660.17728315809, 8761.474311541328, 8826.293896488138, 7428.182899881117, 7599.696012159452, 7601.923980760122, 7631.007968964222, 7637.083549973817, 5760.8271751847515, 5824.0, 5949.6430214187285, 5968.731407086605, 6021.969890150521, 5717.571182161438, 5773.971130144322, 5785.971070144622, 5840.737166827418, 5915.171875937286], "value": 4964.723543388016, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.4483292233659, 348.3312040881508, 352.48889110789355, 356.49911873817297, 360.0584471606749, 5579.38626751057, 5726.3700992890745, 5800.622959507635, 5807.73865176067, 5814.912776308353, 5658.688772117532, 5702.173184888189, 5706.7431965561545, 5713.71431428428, 5713.914291285634, 4474.97762511187, 4491.528389519102, 4524.185646583614, 4524.909501809978, 4528.4339457567885, 4380.102079073782, 4451.465824101112, 4490.146872094308, 4491.326301054835, 4499.965008048151, 4443.84446544371, 4471.7987690554, 4476.306172543261, 4476.485204201526, 4480.9163738861425, 4471.910561788779, 4474.44069491312, 4478.208716513388, 4487.618552423048, 4488.147252022105, 3826.9234615307537, 3970.166265084336, 4009.3585026395635, 4011.4584531088512, 4019.4573732546023, 5543.722813859291, 5553.694546799924, 5562.888742225174, 5563.888722225533, 5578.210894552723, 5226.843194704127, 5237.371515418175, 5237.633365664405, 5241.109011468059, 5256.9474305256845, 5888.852778680524, 5939.70301484924, 5969.224000879858, 6019.909701354483, 6046.425589568984, 4455.754933478657, 4458.4426946631675, 4466.218411777926, 4468.597826195658, 4478.424905679215, 6238.407351301619, 6241.96879015602, 6260.436560709558, 6260.812175634738, 6283.497320214417, 4415.381846541422, 4564.246899261585, 4587.105514424722, 4590.8163673453355, 4602.861914142581, 4663.090697313991, 4673.322368256584, 4673.929891051637, 4678.3216433616935, 4680.625549955996, 5241.344831896013, 5246.868828279247, 5262.368515778131, 5268.236105764643, 5274.630775845731, 5835.795747148914, 5886.58793884424, 5971.2834459864225, 6029.643330250658, 6040.275166979903, 1580.9288582013837, 1600.7118718630759, 1605.4701948357247, 1605.8073031236327, 1613.3949768836687, 1018.4805749068029, 1041.8697662792151, 1055.5408397347026, 1057.6509751781894, 1068.584126032994, 5151.407588127413, 5175.275461435402, 5178.5919547409885, 5199.454057324002, 5224.8171314004585, 5407.405185429599, 5444.7822087116865, 5464.371597266365, 5477.7261136943, 5490.423505531941, 4980.153373926369, 5143.27994080829, 5144.408393034774, 5150.4334523202415, 5178.637495375363, 2891.4506243813607, 2903.666078401011, 2915.99398207618, 2916.9708302916697, 2925.312551550384, 5519.227308176858, 5573.60984731073, 5601.887962240732, 5632.943670563324, 5633.521150702154, 5185.351831021122, 5501.697406642673, 5582.91625625616, 5600.947483621355, 5602.943970560324, 5601.887962240732, 5603.2435621191435, 5614.24207731951, 5635.520980716603, 5645.1532270159805, 5082.949170508396, 5405.405405405324, 5412.161115027177, 5455.672659640434, 5512.834614961557, 5293.523582877559, 5336.919946200887, 5350.732463376893, 5367.35591728995, 5370.570354371719, 5574.358948720786, 5590.888182236414, 5597.608167428241, 5626.324841019104, 5632.380438151718, 4098.4262203292155, 4154.3976123462535, 4169.0411205423525, 4421.137878113737, 4447.6219521341045, 6103.542234332354, 6209.192804935329, 6230.127782110599, 6231.314555398993, 6252.874942501034, 6136.386361363828, 6138.846528836815, 6170.56806023592, 6185.6907154641185, 6196.1635179252025, 5995.340512543704, 6111.022236442148, 6149.477294429845, 6158.599691020069, 6176.702892392648, 6039.758409663565, 6047.0, 6048.969755151196, 6074.906516827014, 6087.8782424350375, 6184.412480814315, 6194.442500175006, 6203.379662033761, 6204.3795620437595, 6210.161628180319, 5911.019808364246, 6042.093685947143, 6053.184044786459, 6056.395055310434, 6074.210352654303], "value": 4473.522693870052, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.2293455618997, 831.4263158420686, 863.1929146248257, 907.0793144957867, 916.657572841539, 16381.918090409546, 17713.291468341282, 17777.11114444279, 17896.19467123979, 17949.820501794977, 14423.63940901478, 14443.350049247783, 14480.2759862007, 14498.057626254285, 14540.0, 852.7313896122718, 883.6421249394006, 884.1158841158841, 884.5179377239388, 885.6191837509868, 867.7873920889359, 880.2341962492641, 896.6727144592243, 897.757605446529, 908.9727308180732, 932.4591736792678, 934.7569631895722, 936.2556767369923, 941.7174847545743, 941.7598512379334, 940.5438362394233, 940.9012053734363, 941.1623654947102, 941.4210260689695, 944.211583327922, 691.0, 691.6126968897383, 692.1210063219683, 693.2097408953797, 693.750249910032, 16318.102504362256, 16426.917865410596, 16463.506094817174, 16472.341106355732, 16543.420980265753, 16148.426397956837, 16569.751453728084, 16590.419335323204, 16737.828352015364, 16873.493795186165, 12805.423755931006, 13040.608781736562, 13365.66585835352, 13396.39716212763, 13697.178169309773, 4155.355919832431, 4185.058361868555, 4235.237657221699, 4235.53409124996, 4266.1467706458525, 88522.11477885203, 89306.0, 89533.0, 90212.0, 90943.54528227316, 11590.246633968765, 11768.352740599177, 11780.352080635646, 11820.527178912747, 12025.398730063462, 21179.258725944517, 21243.36269911905, 21265.36203913885, 21321.573568528853, 21344.35966920995, 9400.482973436527, 9421.670241541513, 9491.905080949102, 9608.471534065693, 9626.711198664052, 4943.456219815817, 4991.0, 5011.120378092838, 5022.146235140035, 5127.66670166438, 4111.362738775495, 4119.938200926988, 4229.97885010573, 4247.5540068292385, 4258.42511260982, 743.2159072178903, 745.1914672580311, 753.6269546574481, 757.4773406349601, 757.8825282081286, 645.6190847399963, 696.3176087434342, 708.3235510087853, 759.6163937211783, 796.1799346672937, 10426.84751171839, 10663.306885052447, 10813.513391897384, 10920.50857711389, 10952.069074128629, 634.4607083978582, 693.4521727834954, 711.9608421536865, 719.2412005334422, 740.8703476891523, 4782.473927867932, 4965.602751779849, 5070.974645126825, 5294.54996325309, 5432.483914028161, 115053.0, 115623.42188289005, 120616.0, 121902.0, 123195.15207271898, 104054.0, 104448.47775761224, 104547.0, 104781.47609261905, 108288.99999999843, 90988.54505727428, 92464.0, 93586.5320673406, 94707.0, 97794.99999999857, 9885.456299903575, 10080.193584513074, 10098.49507524606, 10102.39385636864, 10104.848427273744, 53206.66983325448, 54209.18686219789, 54587.0, 54753.0, 55286.72356638191, 76330.0, 78972.60513697394, 79291.0, 80883.0, 81936.0, 12719.491220351083, 13117.278549679477, 13230.536931207362, 13247.536336228186, 13290.867091328962, 13377.0, 13701.588952331445, 14142.0, 14337.784933225801, 14341.78487322712, 12687.873121268669, 12842.614721558368, 13006.47974081064, 13495.39270732839, 13943.721125577229, 12850.614481565568, 13327.733445331232, 13440.0, 13659.658508536968, 13712.314384280946, 13814.930925345308, 13937.58187254384, 14094.718105638034, 14112.576622701336, 14321.713565728836, 10372.844407333745, 10389.532471038972, 10432.217583681102, 11028.0074793269, 11037.668869933916, 10168.28821982454, 10456.947715261374, 11085.55175769074, 11178.27341222818, 11187.72030699239], "value": 8051.999907073248, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 106.0, 112.0, 113.0, 114.0, 114.0, 112.0, 111.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 140.0, 178.0, 119.0, 140.0, 145.0, 119.0, 173.0, 119.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 162.0, 163.0, 165.0, 161.0, 161.0, 162.0, 162.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 77.0, 182.0, 77.0, 85.0, 76.0, 76.0, 179.0, 77.0], "value": 98.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 157.0, 83.0, 84.0, 83.0, 139.0, 85.0, 86.0, 151.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 117.0, 118.0, 119.0, 119.0, 117.0, 118.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 97.0, 96.0, 95.0, 95.0, 99.0, 97.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 56.0, 51.0, 50.0, 50.0, 51.0, 51.0, 50.0, 52.0, 54.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 60.0, 63.0, 61.0, 61.0, 61.0, 61.0, 63.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 75.0, 77.0, 79.0, 74.0, 72.0, 74.0, 79.0, 83.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 129.0, 141.0, 125.0, 128.0, 119.0, 131.0, 119.0, 158.0, 137.0], "value": 130.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 158.0, 165.0, 151.0, 154.0, 150.0, 157.0, 155.0, 162.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 61.0, 65.0, 61.0, 63.0, 60.0, 63.0], "value": 62.2, "unit": "score"}]}]} 22:14:44 INFO - Return code: 0 22:14:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:14:44 ERROR - # TBPL SUCCESS # 22:14:44 INFO - Running post-action listener: _resource_record_post_action 22:14:44 INFO - [mozharness: 2016-10-27 05:14:44.199466Z] Finished run-tests step (success) 22:14:44 INFO - Running post-run listener: _resource_record_post_run 22:14:44 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290881536; Read time: 12; Write time: 182544 22:14:44 INFO - TinderboxPrint: CPU usage
13.3% 22:14:44 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:14:44 INFO - TinderboxPrint: I/O write bytes / time
290,881,536 / 182,544 22:14:44 INFO - TinderboxPrint: CPU idle
8,192.2 (86.7%) 22:14:44 INFO - TinderboxPrint: CPU user
1,199.3 (12.7%) 22:14:44 INFO - TinderboxPrint: Swap in / out
0 / 0 22:14:44 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71290880; Read time: 0; Write time: 39128 22:14:44 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 219590656; Read time: 0; Write time: 143416 22:14:44 INFO - Running post-run listener: _upload_blobber_files 22:14:44 INFO - Blob upload gear active. 22:14:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:14:44 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:14:44 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:14:44 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:14:45 INFO - (blobuploader) - INFO - Open directory for files ... 22:14:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:14:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:46 INFO - (blobuploader) - INFO - Done attempting. 22:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:14:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:49 INFO - (blobuploader) - INFO - Done attempting. 22:14:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:14:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:14:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:14:49 INFO - (blobuploader) - INFO - Done attempting. 22:14:49 INFO - (blobuploader) - INFO - Iteration through files over. 22:14:50 INFO - Return code: 0 22:14:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:14:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:14:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b7ae475f426bbbcf69a730ce173048015b9ed6d5d9c19c20fd57397172cd1155efaad5d88524c471c926da70694607665f8516ed1cc57edc2159f0cf7d34241d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32649168ac5870dd50ab6772c4f5ba93a761257910e5065d004c17a31d6098def55c3e2b5f5ab8373b060cfdf95ff5deebfebe6b49d94e6f283ac7163e1a4176", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc16e6bb397482ba2f430d8f72d81e63c7cdb5f761752250143e42ede6cadb1fc8d02d2ab88bc982285751982d7ee3c4d2f21d6d16bf7a09a99e3483ca781b5c"} 22:14:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:14:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:14:50 INFO - Contents: 22:14:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b7ae475f426bbbcf69a730ce173048015b9ed6d5d9c19c20fd57397172cd1155efaad5d88524c471c926da70694607665f8516ed1cc57edc2159f0cf7d34241d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32649168ac5870dd50ab6772c4f5ba93a761257910e5065d004c17a31d6098def55c3e2b5f5ab8373b060cfdf95ff5deebfebe6b49d94e6f283ac7163e1a4176", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc16e6bb397482ba2f430d8f72d81e63c7cdb5f761752250143e42ede6cadb1fc8d02d2ab88bc982285751982d7ee3c4d2f21d6d16bf7a09a99e3483ca781b5c"} 22:14:50 INFO - Running post-run listener: copy_logs_to_upload_dir 22:14:50 INFO - Copying logs to upload dir... 22:14:50 INFO - mkdir: /builds/slave/test/build/upload/logs 22:14:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1671.475085 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 51 secs) (at 2016-10-26 22:14:49.756815) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:14:49.760036) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b7ae475f426bbbcf69a730ce173048015b9ed6d5d9c19c20fd57397172cd1155efaad5d88524c471c926da70694607665f8516ed1cc57edc2159f0cf7d34241d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32649168ac5870dd50ab6772c4f5ba93a761257910e5065d004c17a31d6098def55c3e2b5f5ab8373b060cfdf95ff5deebfebe6b49d94e6f283ac7163e1a4176", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc16e6bb397482ba2f430d8f72d81e63c7cdb5f761752250143e42ede6cadb1fc8d02d2ab88bc982285751982d7ee3c4d2f21d6d16bf7a09a99e3483ca781b5c"} build_url:https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/TA05Q8oYRWK8AzR4hAdjjg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b7ae475f426bbbcf69a730ce173048015b9ed6d5d9c19c20fd57397172cd1155efaad5d88524c471c926da70694607665f8516ed1cc57edc2159f0cf7d34241d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/32649168ac5870dd50ab6772c4f5ba93a761257910e5065d004c17a31d6098def55c3e2b5f5ab8373b060cfdf95ff5deebfebe6b49d94e6f283ac7163e1a4176", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc16e6bb397482ba2f430d8f72d81e63c7cdb5f761752250143e42ede6cadb1fc8d02d2ab88bc982285751982d7ee3c4d2f21d6d16bf7a09a99e3483ca781b5c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 22:14:49.785693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:14:49.786018) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1477543616.755574-305607425 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 22:14:49.871229) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:14:49.871580) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 22:14:49.871873) ========= ========= Total master_lag: 0.17 =========