builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1476141888.03 results: success (0) buildid: 20161010144024 builduid: d2eb680b40f54b01b672b0cd08617d41 revision: 70abfe99097824fd510544b188f24c588fd6d5a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.033287) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.033922) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.034334) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.049796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.050137) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-10 16:24:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-10-10 16:24:48 (43.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121494 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.185584) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.185901) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020936 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:48.235720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 16:24:48.236064) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 70abfe99097824fd510544b188f24c588fd6d5a0 --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 70abfe99097824fd510544b188f24c588fd6d5a0 --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=31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-10 16:24:48,864 truncating revision to first 12 chars 2016-10-10 16:24:48,864 Setting DEBUG logging. 2016-10-10 16:24:48,864 attempt 1/10 2016-10-10 16:24:48,864 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/70abfe990978?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 16:24:49,985 unpacking tar archive at: mozilla-beta-70abfe990978/testing/mozharness/ program finished with exit code 0 elapsedTime=1.473163 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-10 16:24:49.724109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:49.724621) ========= script_repo_revision: 70abfe99097824fd510544b188f24c588fd6d5a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:49.725233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:49.725694) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 16:24:49.736307) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-10 16:24:49.736787) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:24:50 INFO - MultiFileLogger online at 20161010 16:24:50 in /builds/slave/test 16:24:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:24:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:24:50 INFO - {'append_to_log': False, 16:24:50 INFO - 'base_work_dir': '/builds/slave/test', 16:24:50 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:24:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:24:50 INFO - 'branch': 'Mozilla-Beta', 16:24:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:24:50 INFO - 'config_files': ('talos/linux_config.py',), 16:24:50 INFO - 'default_actions': ('clobber', 16:24:50 INFO - 'read-buildbot-config', 16:24:50 INFO - 'download-and-extract', 16:24:50 INFO - 'populate-webroot', 16:24:50 INFO - 'create-virtualenv', 16:24:50 INFO - 'install', 16:24:50 INFO - 'run-tests'), 16:24:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:24:50 INFO - 'download_minidump_stackwalk': True, 16:24:50 INFO - 'download_symbols': 'ondemand', 16:24:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:24:50 INFO - 'tooltool.py': '/tools/tooltool.py', 16:24:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:24:50 INFO - '/tools/misc-python/virtualenv.py')}, 16:24:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:24:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:24:50 INFO - 'installer_path': 'installer.exe', 16:24:50 INFO - 'log_level': 'info', 16:24:50 INFO - 'log_name': 'talos', 16:24:50 INFO - 'log_to_console': True, 16:24:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:24:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:24:50 INFO - 'opt_config_files': (), 16:24:50 INFO - 'pip_index': False, 16:24:50 INFO - 'sps_profile': False, 16:24:50 INFO - 'sps_profile_interval': 0, 16:24:50 INFO - 'suite': 'dromaeojs', 16:24:50 INFO - 'system_bits': '32', 16:24:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:24:50 INFO - 'title': 'talos-linux64-ix-014', 16:24:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:24:50 INFO - 'use_talos_json': True, 16:24:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:24:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:24:50 INFO - 'work_dir': 'build'} 16:24:50 INFO - [mozharness: 2016-10-10 23:24:50.461751Z] Running clobber step. 16:24:50 INFO - Running pre-action listener: _resource_record_pre_action 16:24:50 INFO - Running main action method: clobber 16:24:50 INFO - rmtree: /builds/slave/test/build 16:24:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:24:50 INFO - Running post-action listener: _resource_record_post_action 16:24:50 INFO - [mozharness: 2016-10-10 23:24:50.920472Z] Finished clobber step (success) 16:24:50 INFO - [mozharness: 2016-10-10 23:24:50.920573Z] Running read-buildbot-config step. 16:24:50 INFO - Running pre-action listener: _resource_record_pre_action 16:24:50 INFO - Running main action method: read_buildbot_config 16:24:50 INFO - Using buildbot properties: 16:24:50 INFO - { 16:24:50 INFO - "project": "", 16:24:50 INFO - "product": "firefox", 16:24:50 INFO - "script_repo_revision": "production", 16:24:50 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:24:50 INFO - "repository": "", 16:24:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:24:50 INFO - "buildid": "20161010144024", 16:24:50 INFO - "pgo_build": "True", 16:24:50 INFO - "basedir": "/builds/slave/test", 16:24:50 INFO - "buildnumber": 272, 16:24:50 INFO - "slavename": "talos-linux64-ix-014", 16:24:50 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:24:50 INFO - "platform": "ubuntu64_hw", 16:24:50 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:24:50 INFO - "branch": "mozilla-beta", 16:24:50 INFO - "repo_path": "releases/mozilla-beta", 16:24:50 INFO - "revision": "70abfe99097824fd510544b188f24c588fd6d5a0", 16:24:50 INFO - "stage_platform": "linux64-pgo", 16:24:50 INFO - "builduid": "d2eb680b40f54b01b672b0cd08617d41", 16:24:50 INFO - "slavebuilddir": "test" 16:24:50 INFO - } 16:24:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 16:24:50 INFO - Running post-action listener: _resource_record_post_action 16:24:50 INFO - [mozharness: 2016-10-10 23:24:50.923058Z] Finished read-buildbot-config step (success) 16:24:50 INFO - [mozharness: 2016-10-10 23:24:50.923147Z] Running download-and-extract step. 16:24:50 INFO - Running pre-action listener: _resource_record_pre_action 16:24:50 INFO - Running main action method: download_and_extract 16:24:50 INFO - mkdir: /builds/slave/test/build/tests 16:24:50 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')]} 16:24:50 INFO - https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:24:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:24:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:24:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:24:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:24:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:24:50 INFO - retry: Failed, sleeping 30 seconds before retrying 16:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:25:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:25:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:25:20 INFO - retry: Failed, sleeping 60 seconds before retrying 16:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:26:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:26:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:26:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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! 16:26:21 INFO - Caught exception: HTTP Error 404: Not Found 16:26:21 INFO - Caught exception: HTTP Error 404: Not Found 16:26:21 INFO - Caught exception: HTTP Error 404: Not Found 16:26:21 INFO - trying https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:26:21 INFO - Downloading https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:26:21 INFO - Downloaded 1327 bytes. 16:26:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 16:26:21 INFO - Using the following test package requirements: 16:26:21 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:26:21 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:26:21 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 16:26:21 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'jsshell-linux-x86_64.zip'], 16:26:21 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:26:21 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 16:26:21 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 16:26:21 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 16:26:21 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:26:21 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 16:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:26:21 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:26:21 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')]} 16:26:21 INFO - https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:26:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:26:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:26:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:26:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:26:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:26:21 INFO - retry: Failed, sleeping 30 seconds before retrying 16:26:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:26:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:26:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:26:51 INFO - retry: Failed, sleeping 60 seconds before retrying 16:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:27:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:27:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:27:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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! 16:27:51 INFO - Caught exception: HTTP Error 404: Not Found 16:27:51 INFO - Caught exception: HTTP Error 404: Not Found 16:27:51 INFO - Caught exception: HTTP Error 404: Not Found 16:27:51 INFO - trying https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:27:51 INFO - Downloading https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:27:52 INFO - Downloaded 21774323 bytes. 16:27:52 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'] 16:27:52 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 16:27:53 INFO - Return code: 0 16:27:53 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 16:27:53 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')]} 16:27:53 INFO - https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:27:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:27:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:27:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:27:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:27:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:27:53 INFO - retry: Failed, sleeping 30 seconds before retrying 16:28:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:28:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:28:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:28:23 INFO - retry: Failed, sleeping 60 seconds before retrying 16:29:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:29:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:29:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:29:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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! 16:29:23 INFO - Caught exception: HTTP Error 404: Not Found 16:29:23 INFO - Caught exception: HTTP Error 404: Not Found 16:29:23 INFO - Caught exception: HTTP Error 404: Not Found 16:29:23 INFO - trying https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 16:29:23 INFO - Downloading https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:29:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:29:24 INFO - Downloaded 21774323 bytes. 16:29:24 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'] 16:29:24 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 16:29:26 INFO - Return code: 0 16:29:26 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')]} 16:29:26 INFO - https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:29:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:29:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:29:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:29:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:29:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:29:26 INFO - retry: Failed, sleeping 30 seconds before retrying 16:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:29:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:29:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:29:56 INFO - retry: Failed, sleeping 60 seconds before retrying 16:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:30:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:30:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:30:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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! 16:30:56 INFO - Caught exception: HTTP Error 404: Not Found 16:30:56 INFO - Caught exception: HTTP Error 404: Not Found 16:30:56 INFO - Caught exception: HTTP Error 404: Not Found 16:30:56 INFO - trying https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 16:30:56 INFO - Downloading https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:30:56 INFO - Downloaded 14413740 bytes. 16:30:56 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'] 16:30:56 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 16:30:57 INFO - Return code: 0 16:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:57 INFO - https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:30:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:30:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:30:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:30:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:30:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:30:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 16:30:57 INFO - retry: Failed, sleeping 30 seconds before retrying 16:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:31:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:31:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 16:31:27 INFO - retry: Failed, sleeping 60 seconds before retrying 16:32:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:32:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:32:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:32:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BXAOkFL2TKar9ROcGcXOtw/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! 16:32:27 INFO - Caught exception: HTTP Error 404: Not Found 16:32:27 INFO - Caught exception: HTTP Error 404: Not Found 16:32:27 INFO - Caught exception: HTTP Error 404: Not Found 16:32:27 INFO - trying https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:32:27 INFO - Downloading https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:32:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/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 16:32:28 INFO - Downloaded 56803015 bytes. 16:32:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:32:28 INFO - mkdir: /builds/slave/test/properties 16:32:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:32:28 INFO - Writing to file /builds/slave/test/properties/build_url 16:32:28 INFO - Contents: 16:32:28 INFO - build_url:https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:32:29 INFO - Running post-action listener: _resource_record_post_action 16:32:29 INFO - Running post-action listener: set_extra_try_arguments 16:32:29 INFO - [mozharness: 2016-10-10 23:32:29.218966Z] Finished download-and-extract step (success) 16:32:29 INFO - [mozharness: 2016-10-10 23:32:29.219199Z] Running populate-webroot step. 16:32:29 INFO - Running pre-action listener: _resource_record_pre_action 16:32:29 INFO - Running main action method: populate_webroot 16:32:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:32:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:32:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:32:29 INFO - '--tppagecycles', 16:32:29 INFO - '7'], 16:32:29 INFO - 'tests': ['tsvgm']}}, 16:32:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:32:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:32:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:32:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:32:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:32:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['damp', 'tps']}, 16:32:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'tests': ['damp', 'tps']}, 16:32:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['dromaeo_dom']}, 16:32:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:32:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['basic_compositor_video']}, 16:32:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:32:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['a11yr', 16:32:29 INFO - 'ts_paint', 16:32:29 INFO - 'tpaint', 16:32:29 INFO - 'sessionrestore', 16:32:29 INFO - 'sessionrestore_no_auto_restore', 16:32:29 INFO - 'tabpaint']}, 16:32:29 INFO - 'other-e10s': {'tests': ['a11yr', 16:32:29 INFO - 'ts_paint', 16:32:29 INFO - 'tpaint', 16:32:29 INFO - 'sessionrestore', 16:32:29 INFO - 'sessionrestore_no_auto_restore', 16:32:29 INFO - 'tabpaint']}, 16:32:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:32:29 INFO - 'ts_paint', 16:32:29 INFO - 'tpaint', 16:32:29 INFO - 'sessionrestore', 16:32:29 INFO - 'sessionrestore_no_auto_restore', 16:32:29 INFO - 'tabpaint']}, 16:32:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:32:29 INFO - 'ts_paint', 16:32:29 INFO - 'tpaint', 16:32:29 INFO - 'sessionrestore', 16:32:29 INFO - 'sessionrestore_no_auto_restore', 16:32:29 INFO - 'tabpaint']}, 16:32:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['a11yr', 16:32:29 INFO - 'ts_paint', 16:32:29 INFO - 'tpaint', 16:32:29 INFO - 'sessionrestore', 16:32:29 INFO - 'sessionrestore_no_auto_restore', 16:32:29 INFO - 'tabpaint']}, 16:32:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['a11yr', 16:32:29 INFO - 'ts_paint', 16:32:29 INFO - 'tpaint', 16:32:29 INFO - 'sessionrestore', 16:32:29 INFO - 'sessionrestore_no_auto_restore', 16:32:29 INFO - 'tabpaint']}, 16:32:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['tsvgx', 16:32:29 INFO - 'tsvgr_opacity', 16:32:29 INFO - 'tart', 16:32:29 INFO - 'tscrollx', 16:32:29 INFO - 'cart']}, 16:32:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:32:29 INFO - 'tsvgr_opacity', 16:32:29 INFO - 'tart', 16:32:29 INFO - 'tscrollx', 16:32:29 INFO - 'cart']}, 16:32:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'talos_options': ['--disable-e10s'], 16:32:29 INFO - 'tests': ['tp5o']}, 16:32:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'tests': ['tp5o']}, 16:32:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'talos_options': ['--disable-e10s', 16:32:29 INFO - '--xperf_path', 16:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:29 INFO - 'tests': ['tp5n']}, 16:32:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:32:29 INFO - 'talos_options': ['--xperf_path', 16:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:32:29 INFO - 'tests': ['tp5n']}}, 16:32:29 INFO - 'talos.zip': {'path': '', 16:32:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:32:29 INFO - Running post-action listener: _resource_record_post_action 16:32:29 INFO - [mozharness: 2016-10-10 23:32:29.241485Z] Finished populate-webroot step (success) 16:32:29 INFO - [mozharness: 2016-10-10 23:32:29.241575Z] Running create-virtualenv step. 16:32:29 INFO - Running pre-action listener: _resource_record_pre_action 16:32:29 INFO - Running main action method: create_virtualenv 16:32:29 INFO - Creating virtualenv /builds/slave/test/build/venv 16:32:29 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 16:32:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:32:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:32:29 INFO - Using real prefix '/usr' 16:32:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:32:29 INFO - Installing distribute.............................................................................................................................................................................................done. 16:32:31 INFO - Installing pip.................done. 16:32:31 INFO - Return code: 0 16:32:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:32:31 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')]} 16:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:31 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')]} 16:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:31 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:31 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 16:32:31 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 16:32:31 INFO - Using env: {'DISPLAY': ':0', 16:32:31 INFO - 'HOME': '/home/cltbld', 16:32:31 INFO - 'LANG': 'en_US.UTF-8', 16:32:31 INFO - 'LANGUAGE': 'en_US:en', 16:32:31 INFO - 'LOGNAME': 'cltbld', 16:32:31 INFO - 'MAIL': '/var/mail/cltbld', 16:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:31 INFO - 'MOZ_NO_REMOTE': '1', 16:32:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:31 INFO - 'NO_EM_RESTART': '1', 16:32:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:31 INFO - 'PWD': '/builds/slave/test', 16:32:31 INFO - 'SHELL': '/bin/bash', 16:32:31 INFO - 'SHLVL': '1', 16:32:31 INFO - 'TERM': 'linux', 16:32:31 INFO - 'TMOUT': '86400', 16:32:31 INFO - 'USER': 'cltbld', 16:32:31 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:31 INFO - '_': '/tools/buildbot/bin/python'} 16:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:31 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 16:32:31 INFO - Cleaning up... 16:32:32 INFO - Return code: 0 16:32:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:32:32 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')]} 16:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:32 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')]} 16:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:32 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:32 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 16:32:32 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 16:32:32 INFO - Using env: {'DISPLAY': ':0', 16:32:32 INFO - 'HOME': '/home/cltbld', 16:32:32 INFO - 'LANG': 'en_US.UTF-8', 16:32:32 INFO - 'LANGUAGE': 'en_US:en', 16:32:32 INFO - 'LOGNAME': 'cltbld', 16:32:32 INFO - 'MAIL': '/var/mail/cltbld', 16:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:32 INFO - 'MOZ_NO_REMOTE': '1', 16:32:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:32 INFO - 'NO_EM_RESTART': '1', 16:32:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:32 INFO - 'PWD': '/builds/slave/test', 16:32:32 INFO - 'SHELL': '/bin/bash', 16:32:32 INFO - 'SHLVL': '1', 16:32:32 INFO - 'TERM': 'linux', 16:32:32 INFO - 'TMOUT': '86400', 16:32:32 INFO - 'USER': 'cltbld', 16:32:32 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:32 INFO - '_': '/tools/buildbot/bin/python'} 16:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:32 INFO - Downloading/unpacking psutil>=3.1.1 16:32:32 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 16:32:32 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 16:32:32 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 16:32:32 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 16:32:32 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 16:32:32 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 16:32:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:32:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:34 INFO - Installing collected packages: psutil 16:32:34 INFO - Running setup.py install for psutil 16:32:34 INFO - building 'psutil._psutil_linux' extension 16:32:34 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 16:32:34 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 16:32:34 INFO - building 'psutil._psutil_posix' extension 16:32:34 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 16:32:34 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 16:32:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:34 INFO - Successfully installed psutil 16:32:34 INFO - Cleaning up... 16:32:34 INFO - Return code: 0 16:32:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:34 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:34 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 16:32:34 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 16:32:34 INFO - Using env: {'DISPLAY': ':0', 16:32:34 INFO - 'HOME': '/home/cltbld', 16:32:34 INFO - 'LANG': 'en_US.UTF-8', 16:32:34 INFO - 'LANGUAGE': 'en_US:en', 16:32:34 INFO - 'LOGNAME': 'cltbld', 16:32:34 INFO - 'MAIL': '/var/mail/cltbld', 16:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:34 INFO - 'MOZ_NO_REMOTE': '1', 16:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:34 INFO - 'NO_EM_RESTART': '1', 16:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:34 INFO - 'PWD': '/builds/slave/test', 16:32:34 INFO - 'SHELL': '/bin/bash', 16:32:34 INFO - 'SHLVL': '1', 16:32:34 INFO - 'TERM': 'linux', 16:32:34 INFO - 'TMOUT': '86400', 16:32:34 INFO - 'USER': 'cltbld', 16:32:34 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:34 INFO - '_': '/tools/buildbot/bin/python'} 16:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32:35 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 16:32:35 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 16:32:35 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 16:32:35 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 16:32:35 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 16:32:35 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 16:32:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:37 INFO - Installing collected packages: mozsystemmonitor 16:32:37 INFO - Running setup.py install for mozsystemmonitor 16:32:37 INFO - Successfully installed mozsystemmonitor 16:32:37 INFO - Cleaning up... 16:32:37 INFO - Return code: 0 16:32:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:32:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:32:37 INFO - Using env: {'DISPLAY': ':0', 16:32:37 INFO - 'HOME': '/home/cltbld', 16:32:37 INFO - 'LANG': 'en_US.UTF-8', 16:32:37 INFO - 'LANGUAGE': 'en_US:en', 16:32:37 INFO - 'LOGNAME': 'cltbld', 16:32:37 INFO - 'MAIL': '/var/mail/cltbld', 16:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:37 INFO - 'MOZ_NO_REMOTE': '1', 16:32:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:37 INFO - 'NO_EM_RESTART': '1', 16:32:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:37 INFO - 'PWD': '/builds/slave/test', 16:32:37 INFO - 'SHELL': '/bin/bash', 16:32:37 INFO - 'SHLVL': '1', 16:32:37 INFO - 'TERM': 'linux', 16:32:37 INFO - 'TMOUT': '86400', 16:32:37 INFO - 'USER': 'cltbld', 16:32:37 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:37 INFO - '_': '/tools/buildbot/bin/python'} 16:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:37 INFO - Downloading/unpacking jsonschema==2.5.1 16:32:37 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 16:32:37 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 16:32:37 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 16:32:37 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 16:32:37 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 16:32:37 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 16:32:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32:39 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 16:32:39 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 16:32:39 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 16:32:39 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 16:32:39 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 16:32:39 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 16:32:39 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:39 INFO - warning: no files found matching '*.txt' 16:32:39 INFO - no previously-included directories found matching 'build' 16:32:39 INFO - no previously-included directories found matching 'dist' 16:32:39 INFO - no previously-included directories found matching '.git*' 16:32:39 INFO - Installing collected packages: jsonschema, functools32 16:32:39 INFO - Running setup.py install for functools32 16:32:39 INFO - warning: no files found matching '*.txt' 16:32:39 INFO - no previously-included directories found matching 'build' 16:32:39 INFO - no previously-included directories found matching 'dist' 16:32:39 INFO - no previously-included directories found matching '.git*' 16:32:39 INFO - Successfully installed jsonschema functools32 16:32:39 INFO - Cleaning up... 16:32:39 INFO - Return code: 0 16:32:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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', '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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:32: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 functools32==3.2.3-2 16:32:39 INFO - Using env: {'DISPLAY': ':0', 16:32:39 INFO - 'HOME': '/home/cltbld', 16:32:39 INFO - 'LANG': 'en_US.UTF-8', 16:32:39 INFO - 'LANGUAGE': 'en_US:en', 16:32:39 INFO - 'LOGNAME': 'cltbld', 16:32:39 INFO - 'MAIL': '/var/mail/cltbld', 16:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:39 INFO - 'MOZ_NO_REMOTE': '1', 16:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:39 INFO - 'NO_EM_RESTART': '1', 16:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:39 INFO - 'PWD': '/builds/slave/test', 16:32:39 INFO - 'SHELL': '/bin/bash', 16:32:39 INFO - 'SHLVL': '1', 16:32:39 INFO - 'TERM': 'linux', 16:32:39 INFO - 'TMOUT': '86400', 16:32:39 INFO - 'USER': 'cltbld', 16:32:39 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:39 INFO - '_': '/tools/buildbot/bin/python'} 16:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:32:39 INFO - Cleaning up... 16:32:39 INFO - Return code: 0 16:32:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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', '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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:32: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 blobuploader==1.2.4 16:32:39 INFO - Using env: {'DISPLAY': ':0', 16:32:39 INFO - 'HOME': '/home/cltbld', 16:32:39 INFO - 'LANG': 'en_US.UTF-8', 16:32:39 INFO - 'LANGUAGE': 'en_US:en', 16:32:39 INFO - 'LOGNAME': 'cltbld', 16:32:39 INFO - 'MAIL': '/var/mail/cltbld', 16:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:39 INFO - 'MOZ_NO_REMOTE': '1', 16:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:39 INFO - 'NO_EM_RESTART': '1', 16:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:39 INFO - 'PWD': '/builds/slave/test', 16:32:39 INFO - 'SHELL': '/bin/bash', 16:32:39 INFO - 'SHLVL': '1', 16:32:39 INFO - 'TERM': 'linux', 16:32:39 INFO - 'TMOUT': '86400', 16:32:39 INFO - 'USER': 'cltbld', 16:32:39 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:39 INFO - '_': '/tools/buildbot/bin/python'} 16:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 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 16:32:42 INFO - Downloading docopt-0.6.1.tar.gz 16:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:42 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:42 INFO - Running setup.py install for blobuploader 16:32:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:42 INFO - Running setup.py install for requests 16:32:43 INFO - Running setup.py install for docopt 16:32:43 INFO - Successfully installed blobuploader requests docopt 16:32:43 INFO - Cleaning up... 16:32:43 INFO - Return code: 0 16:32:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:43 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:43 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 16:32:43 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 16:32:43 INFO - Using env: {'DISPLAY': ':0', 16:32:43 INFO - 'HOME': '/home/cltbld', 16:32:43 INFO - 'LANG': 'en_US.UTF-8', 16:32:43 INFO - 'LANGUAGE': 'en_US:en', 16:32:43 INFO - 'LOGNAME': 'cltbld', 16:32:43 INFO - 'MAIL': '/var/mail/cltbld', 16:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:43 INFO - 'MOZ_NO_REMOTE': '1', 16:32:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:43 INFO - 'NO_EM_RESTART': '1', 16:32:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:43 INFO - 'PWD': '/builds/slave/test', 16:32:43 INFO - 'SHELL': '/bin/bash', 16:32:43 INFO - 'SHLVL': '1', 16:32:43 INFO - 'TERM': 'linux', 16:32:43 INFO - 'TMOUT': '86400', 16:32:43 INFO - 'USER': 'cltbld', 16:32:43 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:43 INFO - '_': '/tools/buildbot/bin/python'} 16:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:43 INFO - Running setup.py (path:/tmp/pip-jWerAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:43 INFO - Running setup.py (path:/tmp/pip-sZJIbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:43 INFO - Running setup.py (path:/tmp/pip-plKI3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:43 INFO - Running setup.py (path:/tmp/pip-c9jNl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:44 INFO - Running setup.py (path:/tmp/pip-hmDS5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:44 INFO - Running setup.py (path:/tmp/pip-gsHhEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:44 INFO - Running setup.py (path:/tmp/pip-toEx4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:44 INFO - Running setup.py (path:/tmp/pip-cWCgbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:44 INFO - Running setup.py (path:/tmp/pip-ZuDZ1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:44 INFO - Running setup.py (path:/tmp/pip-yXcpgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:44 INFO - Running setup.py (path:/tmp/pip-ih0DnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:44 INFO - Running setup.py (path:/tmp/pip-dqLJzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:44 INFO - Running setup.py (path:/tmp/pip-So9Fo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:44 INFO - Running setup.py (path:/tmp/pip-XqMelE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:44 INFO - Running setup.py (path:/tmp/pip-PLB6bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:44 INFO - Running setup.py (path:/tmp/pip-_zQ5UA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:44 INFO - Running setup.py (path:/tmp/pip-SyYZ4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:44 INFO - Running setup.py install for manifestparser 16:32:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozcrash 16:32:45 INFO - Running setup.py install for mozdebug 16:32:45 INFO - Running setup.py install for mozdevice 16:32:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozfile 16:32:45 INFO - Running setup.py install for mozhttpd 16:32:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozinfo 16:32:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozInstall 16:32:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for mozleak 16:32:45 INFO - Running setup.py install for mozlog 16:32:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:45 INFO - Running setup.py install for moznetwork 16:32:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Running setup.py install for mozprocess 16:32:46 INFO - Running setup.py install for mozprofile 16:32:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Running setup.py install for mozrunner 16:32:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Running setup.py install for mozscreenshot 16:32:46 INFO - Running setup.py install for moztest 16:32:46 INFO - Running setup.py install for mozversion 16:32:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:46 INFO - Cleaning up... 16:32:46 INFO - Return code: 0 16:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:46 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:46 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 16:32:46 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 16:32:46 INFO - Using env: {'DISPLAY': ':0', 16:32:46 INFO - 'HOME': '/home/cltbld', 16:32:46 INFO - 'LANG': 'en_US.UTF-8', 16:32:46 INFO - 'LANGUAGE': 'en_US:en', 16:32:46 INFO - 'LOGNAME': 'cltbld', 16:32:46 INFO - 'MAIL': '/var/mail/cltbld', 16:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:46 INFO - 'MOZ_NO_REMOTE': '1', 16:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:46 INFO - 'NO_EM_RESTART': '1', 16:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:46 INFO - 'PWD': '/builds/slave/test', 16:32:46 INFO - 'SHELL': '/bin/bash', 16:32:46 INFO - 'SHLVL': '1', 16:32:46 INFO - 'TERM': 'linux', 16:32:46 INFO - 'TMOUT': '86400', 16:32:46 INFO - 'USER': 'cltbld', 16:32:46 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:46 INFO - '_': '/tools/buildbot/bin/python'} 16:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:46 INFO - Running setup.py (path:/tmp/pip-WcytgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:47 INFO - Running setup.py (path:/tmp/pip-BKltNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:47 INFO - Running setup.py (path:/tmp/pip-qPlbJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:47 INFO - Running setup.py (path:/tmp/pip-ZINuAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:47 INFO - Running setup.py (path:/tmp/pip-MAuupH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:47 INFO - Running setup.py (path:/tmp/pip-wc2wzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:47 INFO - Running setup.py (path:/tmp/pip-Xyn_EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:47 INFO - Running setup.py (path:/tmp/pip-qUrDoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:47 INFO - Running setup.py (path:/tmp/pip-9d3CFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:47 INFO - Running setup.py (path:/tmp/pip-8LRbXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:47 INFO - Running setup.py (path:/tmp/pip-n8ljp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:47 INFO - Running setup.py (path:/tmp/pip-FfJ4Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:47 INFO - Running setup.py (path:/tmp/pip-LktWSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:47 INFO - Running setup.py (path:/tmp/pip-j7UPvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:47 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)) 16:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:47 INFO - Running setup.py (path:/tmp/pip-4kp3pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:48 INFO - Running setup.py (path:/tmp/pip-DdctCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:48 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)) 16:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:48 INFO - Running setup.py (path:/tmp/pip-gN9y90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:48 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)) 16:32:48 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)) 16:32:48 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)) 16:32:48 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)) 16:32:48 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)) 16:32:48 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)) 16:32:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32: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 16:32:50 INFO - Downloading blessings-1.6.tar.gz 16:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:50 INFO - Installing collected packages: blessings 16:32:50 INFO - Running setup.py install for blessings 16:32:50 INFO - Successfully installed blessings 16:32:50 INFO - Cleaning up... 16:32:50 INFO - Return code: 0 16:32:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:50 INFO - Reading from file tmpfile_stdout 16:32:50 INFO - Current package versions: 16:32:50 INFO - argparse == 1.2.1 16:32:50 INFO - blessings == 1.6 16:32:50 INFO - blobuploader == 1.2.4 16:32:50 INFO - docopt == 0.6.1 16:32:50 INFO - functools32 == 3.2.3-2 16:32:50 INFO - jsonschema == 2.5.1 16:32:50 INFO - manifestparser == 1.1 16:32:50 INFO - mozInstall == 1.12 16:32:50 INFO - mozcrash == 0.17 16:32:50 INFO - mozdebug == 0.1 16:32:50 INFO - mozdevice == 0.48 16:32:50 INFO - mozfile == 1.2 16:32:50 INFO - mozhttpd == 0.7 16:32:50 INFO - mozinfo == 0.9 16:32:50 INFO - mozleak == 0.1 16:32:50 INFO - mozlog == 3.3 16:32:50 INFO - moznetwork == 0.27 16:32:50 INFO - mozprocess == 0.23 16:32:50 INFO - mozprofile == 0.28 16:32:50 INFO - mozrunner == 6.12 16:32:50 INFO - mozscreenshot == 0.1 16:32:50 INFO - mozsystemmonitor == 0.3 16:32:50 INFO - moztest == 0.7 16:32:50 INFO - mozversion == 1.4 16:32:50 INFO - psutil == 3.1.1 16:32:50 INFO - requests == 1.2.3 16:32:50 INFO - wsgiref == 0.1.2 16:32:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:50 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')]} 16:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:50 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')]} 16:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:50 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:50 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 16:32:50 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 16:32:50 INFO - Using env: {'DISPLAY': ':0', 16:32:50 INFO - 'HOME': '/home/cltbld', 16:32:50 INFO - 'LANG': 'en_US.UTF-8', 16:32:50 INFO - 'LANGUAGE': 'en_US:en', 16:32:50 INFO - 'LOGNAME': 'cltbld', 16:32:50 INFO - 'MAIL': '/var/mail/cltbld', 16:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:50 INFO - 'MOZ_NO_REMOTE': '1', 16:32:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:50 INFO - 'NO_EM_RESTART': '1', 16:32:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:50 INFO - 'PWD': '/builds/slave/test', 16:32:50 INFO - 'SHELL': '/bin/bash', 16:32:50 INFO - 'SHLVL': '1', 16:32:50 INFO - 'TERM': 'linux', 16:32:50 INFO - 'TMOUT': '86400', 16:32:50 INFO - 'USER': 'cltbld', 16:32:50 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:50 INFO - '_': '/tools/buildbot/bin/python'} 16:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 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)) 16:32:50 INFO - Cleaning up... 16:32:50 INFO - Return code: 0 16:32:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32:50 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')]} 16:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:50 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')]} 16:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:50 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 0x26ae030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, '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': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', '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 16:32:50 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 16:32:50 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 16:32:50 INFO - Using env: {'DISPLAY': ':0', 16:32:50 INFO - 'HOME': '/home/cltbld', 16:32:50 INFO - 'LANG': 'en_US.UTF-8', 16:32:50 INFO - 'LANGUAGE': 'en_US:en', 16:32:50 INFO - 'LOGNAME': 'cltbld', 16:32:50 INFO - 'MAIL': '/var/mail/cltbld', 16:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:50 INFO - 'MOZ_NO_REMOTE': '1', 16:32:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:50 INFO - 'NO_EM_RESTART': '1', 16:32:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:50 INFO - 'PWD': '/builds/slave/test', 16:32:50 INFO - 'SHELL': '/bin/bash', 16:32:50 INFO - 'SHLVL': '1', 16:32:50 INFO - 'TERM': 'linux', 16:32:50 INFO - 'TMOUT': '86400', 16:32:50 INFO - 'USER': 'cltbld', 16:32:50 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:50 INFO - '_': '/tools/buildbot/bin/python'} 16:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:51 INFO - Cleaning up... 16:32:51 INFO - Return code: 0 16:32:51 INFO - Running post-action listener: _resource_record_post_action 16:32:51 INFO - Running post-action listener: _start_resource_monitoring 16:32:51 INFO - Starting resource monitoring. 16:32:51 INFO - [mozharness: 2016-10-10 23:32:51.064192Z] Finished create-virtualenv step (success) 16:32:51 INFO - [mozharness: 2016-10-10 23:32:51.064627Z] Running install step. 16:32:51 INFO - Running pre-action listener: _resource_record_pre_action 16:32:51 INFO - Running main action method: install 16:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:51 INFO - Reading from file tmpfile_stdout 16:32:51 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:51 INFO - Reading from file tmpfile_stdout 16:32:51 INFO - Output received: 16:32:51 INFO - Usage: mozinstall [options] installer 16:32:51 INFO - Options: 16:32:51 INFO - -h, --help show this help message and exit 16:32:51 INFO - -d DEST, --destination=DEST 16:32:51 INFO - Directory to install application into. [default: 16:32:51 INFO - "/builds/slave/test"] 16:32:51 INFO - --app=APP Application being installed. [default: firefox] 16:32:51 INFO - mkdir: /builds/slave/test/build/application 16:32:51 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'] 16:32:51 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 16:33:05 INFO - Reading from file tmpfile_stdout 16:33:05 INFO - Output received: 16:33:05 INFO - /builds/slave/test/build/application/firefox/firefox 16:33:05 INFO - Running post-action listener: _resource_record_post_action 16:33:05 INFO - [mozharness: 2016-10-10 23:33:05.276346Z] Finished install step (success) 16:33:05 INFO - [mozharness: 2016-10-10 23:33:05.276686Z] Running run-tests step. 16:33:05 INFO - Running pre-action listener: _resource_record_pre_action 16:33:05 INFO - Running main action method: run_tests 16:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:33:05 INFO - Python 2.7.3 16:33:05 INFO - Return code: 0 16:33:05 INFO - grabbing minidump binary from tooltool 16:33: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')]} 16:33:05 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 0x296d3b0>, '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 0x296e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x296e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:33:05 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 16:33:05 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 16:33:05 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 16:33:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:33:05 INFO - Return code: 0 16:33:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:33:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:33:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:33:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:33:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:33:05 INFO - Using env: {'DISPLAY': ':0', 16:33:05 INFO - 'HOME': '/home/cltbld', 16:33:05 INFO - 'LANG': 'en_US.UTF-8', 16:33:05 INFO - 'LANGUAGE': 'en_US:en', 16:33:05 INFO - 'LOGNAME': 'cltbld', 16:33:05 INFO - 'MAIL': '/var/mail/cltbld', 16:33:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:33:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:05 INFO - 'MOZ_NO_REMOTE': '1', 16:33:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:05 INFO - 'NO_EM_RESTART': '1', 16:33:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:05 INFO - 'PWD': '/builds/slave/test', 16:33:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:33:05 INFO - 'SHELL': '/bin/bash', 16:33:05 INFO - 'SHLVL': '1', 16:33:05 INFO - 'TERM': 'linux', 16:33:05 INFO - 'TMOUT': '86400', 16:33:05 INFO - 'USER': 'cltbld', 16:33:05 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335', 16:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:05 INFO - '_': '/tools/buildbot/bin/python'} 16:33:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:33:05 INFO - mozversion application_buildid: 20161010144024 16:33:05 INFO - mozversion application_changeset: 70abfe99097824fd510544b188f24c588fd6d5a0 16:33:05 INFO - mozversion application_display_name: Firefox 16:33:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:33:05 INFO - mozversion application_name: Firefox 16:33:05 INFO - mozversion application_remotingname: firefox 16:33:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:33:05 INFO - mozversion application_vendor: Mozilla 16:33:05 INFO - mozversion application_version: 50.0 16:33:05 INFO - mozversion platform_buildid: 20161010144024 16:33:05 INFO - mozversion platform_changeset: 70abfe99097824fd510544b188f24c588fd6d5a0 16:33:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:33:05 INFO - mozversion platform_version: 50.0 16:33:05 INFO - using testdate: 1476142385 16:33:05 INFO - actual date: 1476142385 16:33:05 INFO - starting webserver on 'localhost:55939' 16:33:05 INFO - SUITE-START | Running 2 tests 16:33:05 INFO - TEST-START | dromaeo_css 16:33:05 INFO - operating with platform_type : linux_ 16:33:05 INFO - Initialising browser for dromaeo_css test... 16:33:05 INFO - TEST-INFO | started process 10423 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps1zwPp/profile http://localhost:55939/getInfo.html) 16:33:09 INFO - PROCESS | 10423 | __metrics Screen width/height:1600/1200 16:33:09 INFO - PROCESS | 10423 | colorDepth:24 16:33:09 INFO - PROCESS | 10423 | Browser inner width/height: 1024/697 16:33:09 INFO - PROCESS | 10423 | __metrics 16:33:10 INFO - PROCESS | 10423 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:33:10 INFO - PROCESS | 10423 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:33:11 INFO - TEST-INFO | 10423: exit 0 16:33:11 INFO - Browser initialized. 16:33:11 INFO - Running cycle 1/1 for dromaeo_css test... 16:33:11 INFO - TEST-INFO | started process 10514 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps1zwPp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:23 INFO - PROCESS | 10514 | RSS: Main: 144527360 16:33:23 INFO - PROCESS | 10514 | 16:34:14 INFO - PROCESS | 10514 | 1476142454762 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] 16:36:22 INFO - PROCESS | 10514 | Cycle 1(1): loaded http://localhost:55939/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55939/tests/dromaeo/cssquery-ext.html) 16:36:23 INFO - PROCESS | 10514 | RSS: Main: 232722432 16:36:23 INFO - PROCESS | 10514 | 16:39:26 INFO - PROCESS | 10514 | Cycle 1(1): loaded http://localhost:55939/tests/dromaeo/cssquery-ext.html (next: http://localhost:55939/tests/dromaeo/cssquery-jquery.html) 16:39:26 INFO - PROCESS | 10514 | RSS: Main: 235511808 16:39:26 INFO - PROCESS | 10514 | 16:42:28 INFO - PROCESS | 10514 | Cycle 1(1): loaded http://localhost:55939/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55939/tests/dromaeo/cssquery-mootools.html) 16:42:29 INFO - PROCESS | 10514 | RSS: Main: 182079488 16:42:29 INFO - PROCESS | 10514 | 16:45:31 INFO - PROCESS | 10514 | Cycle 1(1): loaded http://localhost:55939/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55939/tests/dromaeo/cssquery-prototype.html) 16:45:31 INFO - PROCESS | 10514 | RSS: Main: 181428224 16:45:31 INFO - PROCESS | 10514 | 16:48:34 INFO - PROCESS | 10514 | Cycle 1(1): loaded http://localhost:55939/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55939/tests/dromaeo/cssquery-yui.html) 16:48:34 INFO - PROCESS | 10514 | RSS: Main: 180752384 16:48:34 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | Cycle 1(1): loaded http://localhost:55939/tests/dromaeo/cssquery-yui.html (next: http://localhost:55939/tests/dromaeo/cssquery-dojo.html) 16:51:37 INFO - PROCESS | 10514 | RSS: Main: 264044544 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | __start_tp_report 16:51:37 INFO - PROCESS | 10514 | _x_x_mozilla_page_load 16:51:37 INFO - PROCESS | 10514 | _x_x_mozilla_page_load_details 16:51:37 INFO - PROCESS | 10514 | |i|pagename|runs| 16:51:37 INFO - PROCESS | 10514 | |0;dojo.html;3251.398491279113;3262.119227808491;3282;3295.6869097435765;3322.46840505519;5586.497215250627;5622.437756224381;5644.266245388104;5647.717614119299;5655.236543066685;5618.494335509803;5676.403977582357;5677.006523858318;5684.346300175481;5684.54523638108;5099.949000510003;5115.283860259567;5126.256692779545;5190.974045129769;5203.635745497817;5093.363329583802;5106.106431374513;5209.765560549784;5216.347956505437;5231.503007214308;5064.620153488485;5077.2637967494875;5098.847034588968;5129.615278854101;5134.512221338967;5131.179011358184;5239.816606418795;5245.895082098375;5247.973760131213;5277.076511610491;4985.227289770091;5067.366579177603;5142.100132476801;5174;5202.687838729688;5466.835994920159;5499.587530935196;5499.7250137492965;5513.751881165357;5519.613627046108;5989.341172471025;6014.609050411709;6017.157597936249;6028.577999540034;6032.034874420072;5880.382559831241;5963.13534537494;6077.24034495688;6090;6093;5588.105903055492;5615.438456154352;5622.381538030813;5640.041192997201;5648.435156484319;6138.877222455615;6139.785107521216;6140.201773769381;6144.047672610753;6155.96922015396;5812.070068788974;5821.7380217890295;5875.441833025856;5875.971704951702;5889.152400502175;6006.039033754579;6016.969915150484;6031.366706495754;6057.091436284504;6066.181065556182;5974.402559744078;5987.760489580368;5989;5997.100434934795;6000.489958353589;5826.184334193216;5874.1776151338845;5883.470487656046;5918.644881307136;5993.130996005555;4608.5621865922685;4612.423447069116;4614.400127983341;4615.723056616614;4616.376789133491;4023.1752490739327;4090.9568060144475;4102.3026085564925;4105.4662893823615;4142.813573389205;5770.163326317745;5788;5788.971055144697;5789.131630255411;5796.536277097822;6080.817575472743;6084.939150608526;6088.6042407243385;6092.421219984094;6096.451319381277;5627.155926610959;5729.71351432435;5772;5777.768889244468;5805.506531944832;5309.946900531023;5319.308489896289;5337.572994160458;5345.919811202835;5350.4649535046965;6355.491560675135;6393.520485963571;6399.296077431479;6402.551821372553;6412.422881940554;5954.851128721816;6025;6134.8159555213415;6143.907841382383;6147.969260153671;6246.063090536274;6339.619622822645;6373.107764913008;6386.584871983307;6391.58454700443;5989.341172470938;5995.880082398415;5997.580169388101;6002.339742628395;6042.425969532887;5934.495567876606;6053.606515576474;6078.179445774941;6086.482648974887;6101.084837274444;5907.232059832194;5983.042713165873;5984.371640977634;5998.310194327708;6008.849778755566;5902.61632993854;5938.257717785277;5941.702914854153;5956.106584012433;5964.493018093511;5730.111832665943;5793.739281732248;5924.377940316204;5929.762809487745;5945.346011938596;5867.882642347215;5975.462208401264;5980.372060933713;6012.398760123952;6017.879642407215;5800.8259752207505;5814.825555233349;5818;5822.82531524055;5825.970870145622 16:51:37 INFO - PROCESS | 10514 | |1;ext.html;611.2756383685334;655.4363247607057;658.9505787065968;667.5494041521971;674.6424395070615;34872.3025539489;47032.294515582296;47967.28049079258;48057;51371;47555.76222118893;47914.281285780744;48137.51862481374;48266.51733482664;48287.517124828744;8672.826543469128;9380.108889655488;9538;9543.33196676233;9547.427154370725;10986.945065274662;11287.435628218598;11301.4914328855;11622.593209237679;11628.709282267968;11453.083753299716;11575.594854180106;11578.363190024545;11579.247348922294;11585.073194144448;11403.258788178784;11463.713407164803;11475.196736228467;11481;11483.712907177303;11236.550537978472;11247.887521124765;11299.943500282528;11305.660830175108;11313.886861131366;45462.31806522905;45811.77094114541;45857;45950.77024614889;45964.77017614924;3820.6370394812448;3859.150986782903;3864.5555761087553;3931.567527571965;3947.407888816672;34874.47688284678;35070.298594027976;35458.64541354579;36756;36834.447483287775;4707.599854012413;4808.230683090689;4832.806687732486;4839.879003024917;4860.295257187723;395411;400156;400748;401038;401740;64977;65026;65377;65429.345706543274;65472.69054618976;68889;69050.51171673826;69069;69193.65403173052;69213.30786692169;17155.313787448362;17374;17388.217530211172;17391.65216695659;17397.304107835796;12507.562235321902;12635.810462843065;12848.743025139445;13047.739045219043;13155.671108222372;19376.977848101043;19421.2231510738;19502.609947800964;19513.219471220993;19530;6563.967180164069;6691.130153080068;6740.202815831473;6807.557508762014;6866.965665171742;935.6210734652477;1011.8279892418307;1094.2504384496644;1149.4540093455541;1178.5168081086886;34639.30721385558;34707.4793878092;34813;35196.47205291923;35243.823780881445;1135.4209353229912;1135.784201001807;1156.8843115688533;1209.8608660004213;1215.3680086355046;18085;18415.171317290755;18448.81551184498;18531.90734046321;18555;555252;620718;631496;631716;889578;65346.34653653498;65902;65936.34063659397;65981;66040.6697966507;1334.8464926533572;1434.1825159659102;1458.7009663019055;1474.4913005013468;1474.8009018782327;32353.676463235068;34343.82828085843;37005;37031;37277.6272237285;57903.71048144732;58859.411405887106;58935;59049;59098.704506477196;515777;521205;522097;522905;525230;38815.417768734056;40911.1817763649;48146.27780583224;49864;50121;47172.528274718185;52328.73835630798;52661.10363930021;52802.73598631982;53160.73419632877;43606.78196608997;44564.77717611521;44629.77685111554;46228.306575400726;46258.30612540882;44418.33372499351;45698.543014569426;45730;45757;46302.536974629824;44438;44763.10473790441;45876;45940.77029614831;48295.5170448291;38401.42397863978;39813;40313.1937361257;40820.79589602152;41105.1778964413;29907.850460747555;29934.70065299319;30782.384352313275;30861.382772344878;31079 16:51:37 INFO - PROCESS | 10514 | |2;jquery.html;394.3493236160337;398.94614227079336;418.1615860200298;419.17841031578104;426.0095278477539;19114.522136946583;20290.69563956538;20672.586548269024;20916.581668366623;20949.266775662854;19787.901060494678;19803.70294445581;19963.700544491843;20709.275175368864;20856.68714969273;5457.372402173752;5647.37878833328;5654.264945557072;5655.434456554352;5658.122990936391;5857.3849745776715;5883.205767221413;5889.087191485305;5921.555883308748;5941.88116237675;5807.332156801979;5897.262842144732;5897.528197744192;5939.613925094855;5949.583529152961;5813.796517121901;5844.444777746107;5849.385814489502;5862.413758624146;5931.703414829241;5248.5538729208065;5289.18017707256;5289.894202115975;5294.92057619136;5297;24196.2741117765;24715.752842471524;24771;24974.1259055934;25007.249782506555;2616.149751330806;2640.854752988585;2706.201670507205;3034.833084180369;3234.175285302233;15163.783950065941;15379.231038448144;15396.153211573359;15674.294656740474;15859.762103568455;2010.2643363848933;2059.1458908831264;2060.5504770069588;2068.1410639569576;2084.4163634182455;80324.59837700774;86023;88071;88246.55876720576;90027.54986225092;11235.157363197794;11774.411279435993;11871.347075910735;11877.406129693481;11924.88075119255;16912.57718557046;16917.154142292835;16944.745828812578;16994.745078823827;17036.574085647957;8082.7171049013;8419.621117049748;8440.53577053268;8647.956760216159;8656.8701469478;8079.5960201989665;8094.473859199251;8105.918940810517;8258.298044666151;8278.172182781673;4847.88891868537;4954.430240522386;5143.6399452037995;5148.17629179337;5183.585313174937;1895.5545446819986;1915.2721965652963;1922.2695375757123;1938.8351990081;1983.335582579823;909;995.571355105426;1003.3779056984716;1042.713253855196;1084.143526613982;19218.519537011405;20811.58376832485;20987.685184722242;21094.156233750786;21176.047077881532;991.4249735153594;1064.2922456566296;1068.371805358826;1096.9012788849043;1153.6827372472637;11019.173561982883;11483.483243254073;11593.420328983517;11702.531898724126;11852.762944741056;413464;414330;422285;517661;526225;89798;98316;101343;101682;102237.48881255546;1051.0803047333584;1083.664064140119;1090.9945450272699;1116.843641890136;1146.9369184694922;16397.91801040987;18675.626487470447;19114;19136.23455061782;19423;43601.56398435975;44561.55438445574;44669.65991020275;44895.55104448914;45681;63127.6843615779;63434.365656342845;63471.6826415865;63541;64511.67744161249;8604.526751028503;8740.781480462785;8983.595738191667;9046.321525885453;9189.127032931861;10097.949510252402;12959;13620.65948351299;13959.302034898417;14203.28983550839;11294.152938529478;11510.136739744385;11547.8267825981;11688.70778230551;11929.719327185094;11280.379579123228;11316;11350.545978161112;11550.88449115498;11942.581830824738;11227.71930701739;11332.320060796379;11430.942845285721;11476.368799716096;11752.529898804294;8720.563971801512;8816.51509167002;8867.911320886966;8907.242884354902;9108.681196158102;9598.42409455435;10453.634122805664;11568.537258509565;11798.174127811315;11865.110116741107 16:51:37 INFO - PROCESS | 10514 | |3;mootools.html;1386.3969173409562;1526.6641338905438;1560.4148444333373;1676.9580760480987;1800.684880145975;8415.579221038946;9345.626174953002;9698.418094914301;9700.514974251277;9976.551055202515;9641.56612952417;9675;9732.221422286219;9733.367331123472;9756.219502439806;2413.2157048959116;2461.90152393904;2463.8521688698734;2469.469064151207;2490.6762120924254;2423.8060955123638;2526.2295000024983;2530.8481491110592;2531.3671582104475;2540.9110681126162;2429.9514009719796;2443.30365845734;2454.337328921199;2458.7172475165316;2458.9508209835794;2354.3290162303833;2379.892904819287;2379.9885048854253;2409.71083469983;2422.4307287787356;2318.2349824557855;2367.9644805327935;2381.404648837791;2387.904483820645;2387.9880600597057;9540.33217674763;9652.469114198717;9675.129238368581;9745.02549745027;9753.170980466668;5498.890022199574;5683;5714.742836572323;5718.885622287573;5724.914126288109;3466.254755227613;3480.2865412590613;3498.4577390504514;3539.7699149555456;3542.8051457169845;1119.4794420594462;1123.1127409346607;1137.9715507112305;1147.8909503597145;1149.367847683779;13832.515861944885;13956;13958;13965.092269002482;13979.440822367093;4598.0803839232285;4623.468301145412;4794.352762377034;4803.5916947059195;4816.470188279286;10857.185711071701;10864.402457864893;10880.183986201067;10882.836757448644;10882.891171088346;5340.679559226382;5362.812301569505;5378.91931621026;5387.865303367447;5400.378956419983;4650.558196971282;4991.50084991498;5065.544101030925;5134.614903882224;5196.45437229093;3959.2279505496153;4081;4094.61101195386;4111.9177616447505;4206.242876282237;1169.8128299472044;1182.8225766134976;1197.8382918305917;1198.161287099034;1219.7560487902279;1032.4992378696327;1036.2797855490362;1058.5871510111062;1059.3167407022427;1068.150820098024;6399.488040956712;6500.187476565429;6551.148350714377;6604;6691.096701945273;973.5229737428609;985.9654912078042;1002.4336250018716;1020.6682828080903;1058.7353161709573;4435.268180750205;4575.5424457554645;4629.213033784265;4672.112298663243;4686.343911852344;11989;11990.820137697941;12339;12480.313582752855;12540.059495537871;12413.75172496545;12429.564965226175;12446.315452650191;12461.688457788447;12465.93767031159;8673.826523469621;9161.312901532412;9213.12475314844;9218.400803947721;9254.074592540826;6599.769008084694;6605.5376123670885;6623;6628.867422651617;6712.295209002984;11442.084633229155;11468.541258349906;11514.596989105341;11542.538298467967;11742;11370.886291137314;11442.885571144516;11678.124140689652;11838.348890810743;11947.462364193805;7448.73929412468;7942.166072562298;8148.348132149295;8310.750677479686;8443.831123377375;9664.71005869825;9706.514674266116;9747.805043899225;9748.80502389934;9774.609015639295;8119.228673276029;8355.248027677539;8576.44253123545;8653.740387788377;8672.739817805475;8022.358211342962;8130.065042520186;8206.671733130608;8431.831363372821;8513.40406171562;7318.121825380988;7358.080239970004;7415.147258065392;7421.628918554159;7441.367483763941;5592.692401917933;5620.915686264628;5660.971695141498;5724.4561766632105;5800.332961709458;5687.77248910039;5771.076627739541;5841.18223448709;5881.588288819741;5904.114382842608 16:51:37 INFO - PROCESS | 10514 | |4;prototype.html;342.71554609673973;347.6002597013435;348.5190437196668;353.4239190120104;358.3120408815073;5383.946160538393;5407.324084489439;5416.052190866598;5438.700871452069;5446.1558458439;5324.334458192726;5348.277982472375;5391.353037635479;5408.376937185462;5437.184422336652;4173.4157217989505;4208.516020657617;4250.214909737918;4257.212415703089;4261.40054566714;4237.703360764748;4245.660347172235;4250.468691413574;4266.957330426702;4303.289957157065;4202.621764041236;4227.408162857202;4256.659467242629;4258.510271318792;4283.785810709452;4045.534763502191;4081.306257902952;4199.488184253666;4234.513031001413;4236.449261596003;3780.865740277933;3790.90522736931;3791.469194312798;3791.9431208531896;3798.4682144499793;5185.066687996159;5211.296474975906;5219.164933610642;5259.894802103937;5299.311089558333;4706.858794236178;4722.693024953402;4792.712437253741;4823.13183626947;4830.975845120752;5496.148097044965;5518.53092487135;5529.502344788948;5534.533348662608;5534.592452283229;4108.50697916249;4145.606167414091;4155.439015732898;4165.58344165581;4174.248635245654;5701.0878259478695;5742.626729262584;5756.956280520397;5759.971200144015;5769.365369809276;4246.787660616957;4273.914521709548;4275.615194632436;4281.993731473101;4290;4341.435613370305;4348.173846969065;4349.869503914888;4370.125974805052;4380.70769123103;4715.339852420664;4757.738324392122;4767.547083027107;4799.952000480021;4938.506149385104;5539.639923404966;5548.334199896039;5574.554035677217;5584.301962254718;5596.321103668883;1487.7056960444265;1497.1466264229284;1500.6848561802144;1504.277946585623;1504.353128154904;919.4896832258161;971.6939164163407;978.0122076702439;979.2802290316612;992.8014397120605;5410.729463526808;5438.320209973754;5441.918371224356;5446.945530544644;5455;5669.06460434032;5678.176664383641;5678.943210567842;5701.947298972366;5710.2862142232225;5333.359996800409;5442.537384322377;5444.265024221759;5452.982396513236;5456.972715136399;3004.684508126659;3019.3659331540184;3019.4112148131344;3030.7423868970945;3030.818150910952;5758.942410575924;5926.2295901532525;5944.375840536768;5956.5532585055425;5979.820605381846;5890.087036509348;5940.673262970492;5953.880922381528;5956.851078723066;5960.6721630309885;6026.890588293974;6079.604825686316;6091.939080609226;6092.908606370908;6168.784092556739;5578.888422231614;5778.94221057784;5807.0998995155815;5815.505682016907;5865.560082993878;5733.971330143323;5746.224259724883;5747.252857128547;5752.9712351437975;5764.827055188351;5847.970760146172;6061.818145455643;6074.3014553325165;6074.544409169418;6128.264608247039;4137.37939309094;4176.519700234512;4177.352510360899;4190.769507676985;4222.176675548239;5884.205632239764;5888.411158884077;5889.911651325147;5903.586748927534;5903.586748927534;5543.584231182758;5687.488126068673;5688.175214593778;5693.914591281217;5722.713864306851;5689.943100568941;5696.629719068247;5698.31620205578;5718.342390625131;5722.570807189394;5376;5587.273654424899;5664.801731939528;5698.1737648041635;5735.426457354231;5282.31329927107;5687.829365119053;5689.857753556194;5691.715414229355;5701.7149142541875;5526.530244929065;5543.473370029841;5551.19586134516;5552.888942221214;5558.166275058774 16:51:37 INFO - PROCESS | 10514 | |5;yui.html;790.1150711203456;821.1501096365259;839.9034111077226;847.5677404523691;896.6592694775986;14775;16298.837011629896;17484.38804641838;17623;17724.37964671235;15965.760513592306;16059.277332520036;16114;16119.113448760314;16123.516294511184;836.991630083699;857.3184318466834;861.6036623153357;865.7229686500323;868.956552172392;864.2826454043129;865.9913400865973;866.0386970462781;866.5797088412147;866.9089745576719;895.4448242089913;900.5902314446911;902.9458232506038;905.0316161706976;905.9682911098113;892.8973168085623;895.6283142495856;900.0414558495214;900.3562452846213;900.6127365232948;663.7477758451757;664.8271449423137;665.8435267712084;665.893457046875;666.1273731411866;16328;16361.100139492322;16423.589410264718;16447.342106315733;16453.670926581402;13960.58118256454;14038.438462461489;14053.437862485489;14201.857981420157;15712.45006424781;14011.509597164051;14107.576772696835;14156.433742650283;14212.360443780055;14371.425142994267;4743.549362810528;4764.475907650156;4800.735959522225;4843.4914333994775;4857.805687772485;58089;71575.28424715738;74143.62928185379;74230.62884685597;87309.56345218234;11592.942035289938;11813.586524471602;11891.702707432383;11907.642770716893;11976;18981.905090474735;18994.240230390908;19006.80993190078;19250.422487325402;19666.311679091163;8589.26991205742;8894.510801905957;8922.643094276285;8984.955075224712;9055.36612437123;4854.538818812207;4992.605207726478;5048.1418158913075;5101.744912754348;5206.193040078794;3820.4078367853026;3960;3961.3661814109605;3965.841366345314;3974.582668819732;692.342274838905;692.5982929900646;700.1388292400376;701.3897908819258;705.0763499815258;777.6461709921972;788.4402074527035;825.2078005115037;842.0905422144192;849.2653854415886;10033.046860548382;10066.496675166212;10215.387076775418;10295.227857910686;10532.36805791655;696.7392602619788;730.6967608442543;734.4565021883874;748.3377211168155;759.6809340077181;4738.763061846894;4741.146593613183;4872.805087796449;4973.626977976666;4986.177280748636;75338;106490.93509064965;130187;131729;135046;71838.9224161638;87467.1253287459;89699.72101952932;115123;115270;86573;87629.37997327637;87856.560717196;90982.54508727413;91722;9628.518574071128;9668.758281042748;9777.462239576891;10434;10623.309484883457;39837.40243896402;50108.24837627366;51091.2336314963;52208.47791522036;52391;69173.65413172902;70054.29945700477;73714.63142684252;77157.228427715;78853;13352;13598.524051658145;13950.372233249731;14283.500077497238;14432.989690721548;15220.543383698507;15452.768208477106;15585.76621350658;15678.216089195266;16157.353705852105;14688.97177197586;14728.926355368154;14730.26348682583;14746.926265368604;14805.925970370079;14657.92671036638;14712.337944792296;14765.926170369079;15038.924805375904;15659.608509787346;14281.928590357398;15004.849951500346;15034.473793417616;15386.538403847902;15850.68298634044;11604.187706860439;11630;12222;12357.38213089324;12483.500659973499;10971.725706857393;11488.138389620644;11535.5962541314;12111;13225 16:51:37 INFO - PROCESS | 10514 | __end_tp_report 16:51:37 INFO - PROCESS | 10514 | __start_cc_report 16:51:37 INFO - PROCESS | 10514 | _x_x_mozilla_cycle_collect,960 16:51:37 INFO - PROCESS | 10514 | __end_cc_report 16:51:37 INFO - PROCESS | 10514 | __startTimestamp1476143497669__endTimestamp 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | ------- Summary: start ------- 16:51:37 INFO - PROCESS | 10514 | Number of tests: 6 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | [#0] dojo.html Cycles:165 Average:5622.14 Median:5822.83 stddev:641.37 (11.0%) stddev-sans-first:615.78 16:51:37 INFO - PROCESS | 10514 | Values: 3251.4 3262.1 3282.0 3295.7 3322.5 5586.5 5622.4 5644.3 5647.7 5655.2 5618.5 5676.4 5677.0 5684.3 5684.5 5099.9 5115.3 5126.3 5191.0 5203.6 5093.4 5106.1 5209.8 5216.3 5231.5 5064.6 5077.3 5098.8 5129.6 5134.5 5131.2 5239.8 5245.9 5248.0 5277.1 4985.2 5067.4 5142.1 5174.0 5202.7 5466.8 5499.6 5499.7 5513.8 5519.6 5989.3 6014.6 6017.2 6028.6 6032.0 5880.4 5963.1 6077.2 6090.0 6093.0 5588.1 5615.4 5622.4 5640.0 5648.4 6138.9 6139.8 6140.2 6144.0 6156.0 5812.1 5821.7 5875.4 5876.0 5889.2 6006.0 6017.0 6031.4 6057.1 6066.2 5974.4 5987.8 5989.0 5997.1 6000.5 5826.2 5874.2 5883.5 5918.6 5993.1 4608.6 4612.4 4614.4 4615.7 4616.4 4023.2 4091.0 4102.3 4105.5 4142.8 5770.2 5788.0 5789.0 5789.1 5796.5 6080.8 6084.9 6088.6 6092.4 6096.5 5627.2 5729.7 5772.0 5777.8 5805.5 5309.9 5319.3 5337.6 5345.9 5350.5 6355.5 6393.5 6399.3 6402.6 6412.4 5954.9 6025.0 6134.8 6143.9 6148.0 6246.1 6339.6 6373.1 6386.6 6391.6 5989.3 5995.9 5997.6 6002.3 6042.4 5934.5 6053.6 6078.2 6086.5 6101.1 5907.2 5983.0 5984.4 5998.3 6008.8 5902.6 5938.3 5941.7 5956.1 5964.5 5730.1 5793.7 5924.4 5929.8 5945.3 5867.9 5975.5 5980.4 6012.4 6017.9 5800.8 5814.8 5818.0 5822.8 5826.0 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | [#1] ext.html Cycles:180 Average:70530.68 Median:41008.18 stddev:144912.39 (353.4%) stddev-sans-first:145223.82 16:51:37 INFO - PROCESS | 10514 | Values: 611.3 655.4 659.0 667.5 674.6 34872.3 47032.3 47967.3 48057.0 51371.0 47555.8 47914.3 48137.5 48266.5 48287.5 8672.8 9380.1 9538.0 9543.3 9547.4 10986.9 11287.4 11301.5 11622.6 11628.7 11453.1 11575.6 11578.4 11579.2 11585.1 11403.3 11463.7 11475.2 11481.0 11483.7 11236.6 11247.9 11299.9 11305.7 11313.9 45462.3 45811.8 45857.0 45950.8 45964.8 3820.6 3859.2 3864.6 3931.6 3947.4 34874.5 35070.3 35458.6 36756.0 36834.4 4707.6 4808.2 4832.8 4839.9 4860.3 395411.0 400156.0 400748.0 401038.0 401740.0 64977.0 65026.0 65377.0 65429.3 65472.7 68889.0 69050.5 69069.0 69193.7 69213.3 17155.3 17374.0 17388.2 17391.7 17397.3 12507.6 12635.8 12848.7 13047.7 13155.7 19377.0 19421.2 19502.6 19513.2 19530.0 6564.0 6691.1 6740.2 6807.6 6867.0 935.6 1011.8 1094.3 1149.5 1178.5 34639.3 34707.5 34813.0 35196.5 35243.8 1135.4 1135.8 1156.9 1209.9 1215.4 18085.0 18415.2 18448.8 18531.9 18555.0 555252.0 620718.0 631496.0 631716.0 889578.0 65346.3 65902.0 65936.3 65981.0 66040.7 1334.8 1434.2 1458.7 1474.5 1474.8 32353.7 34343.8 37005.0 37031.0 37277.6 57903.7 58859.4 58935.0 59049.0 59098.7 515777.0 521205.0 522097.0 522905.0 525230.0 38815.4 40911.2 48146.3 49864.0 50121.0 47172.5 52328.7 52661.1 52802.7 53160.7 43606.8 44564.8 44629.8 46228.3 46258.3 44418.3 45698.5 45730.0 45757.0 46302.5 44438.0 44763.1 45876.0 45940.8 48295.5 38401.4 39813.0 40313.2 40820.8 41105.2 29907.9 29934.7 30782.4 30861.4 31079.0 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | [#2] jquery.html Cycles:180 Average:29154.96 Median:24743.38 stddev:76602.68 (309.6%) stddev-sans-first:76787.13 16:51:37 INFO - PROCESS | 10514 | Values: 394.3 398.9 418.2 419.2 426.0 19114.5 20290.7 20672.6 20916.6 20949.3 19787.9 19803.7 19963.7 20709.3 20856.7 5457.4 5647.4 5654.3 5655.4 5658.1 5857.4 5883.2 5889.1 5921.6 5941.9 5807.3 5897.3 5897.5 5939.6 5949.6 5813.8 5844.4 5849.4 5862.4 5931.7 5248.6 5289.2 5289.9 5294.9 5297.0 24196.3 24715.8 24771.0 24974.1 25007.2 2616.1 2640.9 2706.2 3034.8 3234.2 15163.8 15379.2 15396.2 15674.3 15859.8 2010.3 2059.1 2060.6 2068.1 2084.4 80324.6 86023.0 88071.0 88246.6 90027.5 11235.2 11774.4 11871.3 11877.4 11924.9 16912.6 16917.2 16944.7 16994.7 17036.6 8082.7 8419.6 8440.5 8648.0 8656.9 8079.6 8094.5 8105.9 8258.3 8278.2 4847.9 4954.4 5143.6 5148.2 5183.6 1895.6 1915.3 1922.3 1938.8 1983.3 909.0 995.6 1003.4 1042.7 1084.1 19218.5 20811.6 20987.7 21094.2 21176.0 991.4 1064.3 1068.4 1096.9 1153.7 11019.2 11483.5 11593.4 11702.5 11852.8 413464.0 414330.0 422285.0 517661.0 526225.0 89798.0 98316.0 101343.0 101682.0 102237.5 1051.1 1083.7 1091.0 1116.8 1146.9 16397.9 18675.6 19114.0 19136.2 19423.0 43601.6 44561.6 44669.7 44895.6 45681.0 63127.7 63434.4 63471.7 63541.0 64511.7 8604.5 8740.8 8983.6 9046.3 9189.1 10097.9 12959.0 13620.7 13959.3 14203.3 11294.2 11510.1 11547.8 11688.7 11929.7 11280.4 11316.0 11350.5 11550.9 11942.6 11227.7 11332.3 11430.9 11476.4 11752.5 8720.6 8816.5 8867.9 8907.2 9108.7 9598.4 10453.6 11568.5 11798.2 11865.1 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | [#3] mootools.html Cycles:180 Average:6343.24 Median:4610.77 stddev:3743.49 (81.2%) stddev-sans-first:3735.45 16:51:37 INFO - PROCESS | 10514 | Values: 1386.4 1526.7 1560.4 1677.0 1800.7 8415.6 9345.6 9698.4 9700.5 9976.6 9641.6 9675.0 9732.2 9733.4 9756.2 2413.2 2461.9 2463.9 2469.5 2490.7 2423.8 2526.2 2530.8 2531.4 2540.9 2430.0 2443.3 2454.3 2458.7 2459.0 2354.3 2379.9 2380.0 2409.7 2422.4 2318.2 2368.0 2381.4 2387.9 2388.0 9540.3 9652.5 9675.1 9745.0 9753.2 5498.9 5683.0 5714.7 5718.9 5724.9 3466.3 3480.3 3498.5 3539.8 3542.8 1119.5 1123.1 1138.0 1147.9 1149.4 13832.5 13956.0 13958.0 13965.1 13979.4 4598.1 4623.5 4794.4 4803.6 4816.5 10857.2 10864.4 10880.2 10882.8 10882.9 5340.7 5362.8 5378.9 5387.9 5400.4 4650.6 4991.5 5065.5 5134.6 5196.5 3959.2 4081.0 4094.6 4111.9 4206.2 1169.8 1182.8 1197.8 1198.2 1219.8 1032.5 1036.3 1058.6 1059.3 1068.2 6399.5 6500.2 6551.1 6604.0 6691.1 973.5 986.0 1002.4 1020.7 1058.7 4435.3 4575.5 4629.2 4672.1 4686.3 11989.0 11990.8 12339.0 12480.3 12540.1 12413.8 12429.6 12446.3 12461.7 12465.9 8673.8 9161.3 9213.1 9218.4 9254.1 6599.8 6605.5 6623.0 6628.9 6712.3 11442.1 11468.5 11514.6 11542.5 11742.0 11370.9 11442.9 11678.1 11838.3 11947.5 7448.7 7942.2 8148.3 8310.8 8443.8 9664.7 9706.5 9747.8 9748.8 9774.6 8119.2 8355.2 8576.4 8653.7 8672.7 8022.4 8130.1 8206.7 8431.8 8513.4 7318.1 7358.1 7415.1 7421.6 7441.4 5592.7 5620.9 5661.0 5724.5 5800.3 5687.8 5771.1 5841.2 5881.6 5904.1 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | [#4] prototype.html Cycles:180 Average:4778.35 Median:5443.40 stddev:1388.22 (25.5%) stddev-sans-first:1351.60 16:51:37 INFO - PROCESS | 10514 | Values: 342.7 347.6 348.5 353.4 358.3 5383.9 5407.3 5416.1 5438.7 5446.2 5324.3 5348.3 5391.4 5408.4 5437.2 4173.4 4208.5 4250.2 4257.2 4261.4 4237.7 4245.7 4250.5 4267.0 4303.3 4202.6 4227.4 4256.7 4258.5 4283.8 4045.5 4081.3 4199.5 4234.5 4236.4 3780.9 3790.9 3791.5 3791.9 3798.5 5185.1 5211.3 5219.2 5259.9 5299.3 4706.9 4722.7 4792.7 4823.1 4831.0 5496.1 5518.5 5529.5 5534.5 5534.6 4108.5 4145.6 4155.4 4165.6 4174.2 5701.1 5742.6 5757.0 5760.0 5769.4 4246.8 4273.9 4275.6 4282.0 4290.0 4341.4 4348.2 4349.9 4370.1 4380.7 4715.3 4757.7 4767.5 4800.0 4938.5 5539.6 5548.3 5574.6 5584.3 5596.3 1487.7 1497.1 1500.7 1504.3 1504.4 919.5 971.7 978.0 979.3 992.8 5410.7 5438.3 5441.9 5446.9 5455.0 5669.1 5678.2 5678.9 5701.9 5710.3 5333.4 5442.5 5444.3 5453.0 5457.0 3004.7 3019.4 3019.4 3030.7 3030.8 5758.9 5926.2 5944.4 5956.6 5979.8 5890.1 5940.7 5953.9 5956.9 5960.7 6026.9 6079.6 6091.9 6092.9 6168.8 5578.9 5778.9 5807.1 5815.5 5865.6 5734.0 5746.2 5747.3 5753.0 5764.8 5848.0 6061.8 6074.3 6074.5 6128.3 4137.4 4176.5 4177.4 4190.8 4222.2 5884.2 5888.4 5889.9 5903.6 5903.6 5543.6 5687.5 5688.2 5693.9 5722.7 5689.9 5696.6 5698.3 5718.3 5722.6 5376.0 5587.3 5664.8 5698.2 5735.4 5282.3 5687.8 5689.9 5691.7 5701.7 5526.5 5543.5 5551.2 5552.9 5558.2 16:51:37 INFO - PROCESS | 10514 | 16:51:37 INFO - PROCESS | 10514 | [#5] yui.html Cycles:180 Average:21076.94 Median:4742.35 stddev:29960.60 (631.8%) stddev-sans-first:30005.92 16:51:37 INFO - PROCESS | 10514 | Values: 790.1 821.2 839.9 847.6 896.7 14775.0 16298.8 17484.4 17623.0 17724.4 15965.8 16059.3 16114.0 16119.1 16123.5 837.0 857.3 861.6 865.7 869.0 864.3 866.0 866.0 866.6 866.9 895.4 900.6 902.9 905.0 906.0 892.9 895.6 900.0 900.4 900.6 663.7 664.8 665.8 665.9 666.1 16328.0 16361.1 16423.6 16447.3 16453.7 13960.6 14038.4 14053.4 14201.9 15712.5 14011.5 14107.6 14156.4 14212.4 14371.4 4743.5 4764.5 4800.7 4843.5 4857.8 58089.0 71575.3 74143.6 74230.6 87309.6 11592.9 11813.6 11891.7 11907.6 11976.0 18981.9 18994.2 19006.8 19250.4 19666.3 8589.3 8894.5 8922.6 8985.0 9055.4 4854.5 4992.6 5048.1 5101.7 5206.2 3820.4 3960.0 3961.4 3965.8 3974.6 692.3 692.6 700.1 701.4 705.1 777.6 788.4 825.2 842.1 849.3 10033.0 10066.5 10215.4 10295.2 10532.4 696.7 730.7 734.5 748.3 759.7 4738.8 4741.1 4872.8 4973.6 4986.2 75338.0 106490.9 130187.0 131729.0 135046.0 71838.9 87467.1 89699.7 115123.0 115270.0 86573.0 87629.4 87856.6 90982.5 91722.0 9628.5 9668.8 9777.5 10434.0 10623.3 39837.4 50108.2 51091.2 52208.5 52391.0 69173.7 70054.3 73714.6 77157.2 78853.0 13352.0 13598.5 13950.4 14283.5 14433.0 15220.5 15452.8 15585.8 15678.2 16157.4 14689.0 14728.9 14730.3 14746.9 14805.9 14657.9 14712.3 14765.9 15038.9 15659.6 14281.9 15004.8 15034.5 15386.5 15850.7 11604.2 11630.0 12222.0 12357.4 12483.5 10971.7 11488.1 11535.6 12111.0 13225.0 16:51:37 INFO - PROCESS | 10514 | -------- Summary: end -------- 16:51:37 INFO - PROCESS | 10514 | 16:51:38 INFO - TEST-INFO | 10514: exit 0 16:51:38 INFO - TEST-OK | dromaeo_css | took 1112883ms 16:51:38 INFO - TEST-START | kraken 16:51:38 INFO - operating with platform_type : linux_ 16:51:38 INFO - Initialising browser for kraken test... 16:51:38 INFO - TEST-INFO | started process 10709 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKB6NwE/profile http://localhost:55939/getInfo.html) 16:51:42 INFO - PROCESS | 10709 | __metrics Screen width/height:1600/1200 16:51:42 INFO - PROCESS | 10709 | colorDepth:24 16:51:42 INFO - PROCESS | 10709 | Browser inner width/height: 1024/697 16:51:42 INFO - PROCESS | 10709 | __metrics 16:51:43 INFO - PROCESS | 10709 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:51:43 INFO - PROCESS | 10709 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:51:44 INFO - TEST-INFO | 10709: exit 0 16:51:44 INFO - Browser initialized. 16:51:44 INFO - Running cycle 1/1 for kraken test... 16:51:44 INFO - TEST-INFO | started process 10792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKB6NwE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:55 INFO - PROCESS | 10792 | RSS: Main: 144101376 16:51:55 INFO - PROCESS | 10792 | 16:51:58 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55939/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:59 INFO - PROCESS | 10792 | RSS: Main: 198758400 16:51:59 INFO - PROCESS | 10792 | 16:52:01 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55939/tests/kraken/driver.html?testName=audio-dft) 16:52:02 INFO - PROCESS | 10792 | RSS: Main: 182996992 16:52:02 INFO - PROCESS | 10792 | 16:52:05 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55939/tests/kraken/driver.html?testName=audio-fft) 16:52:05 INFO - PROCESS | 10792 | RSS: Main: 275066880 16:52:05 INFO - PROCESS | 10792 | 16:52:07 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55939/tests/kraken/driver.html?testName=audio-oscillator) 16:52:08 INFO - PROCESS | 10792 | RSS: Main: 179744768 16:52:08 INFO - PROCESS | 10792 | 16:52:10 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55939/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:52:11 INFO - PROCESS | 10792 | RSS: Main: 178872320 16:52:11 INFO - PROCESS | 10792 | 16:52:14 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55939/tests/kraken/driver.html?testName=imaging-darkroom) 16:52:14 INFO - PROCESS | 10792 | RSS: Main: 214945792 16:52:14 INFO - PROCESS | 10792 | 16:52:18 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55939/tests/kraken/driver.html?testName=imaging-desaturate) 16:52:18 INFO - PROCESS | 10792 | RSS: Main: 213811200 16:52:18 INFO - PROCESS | 10792 | 16:52:21 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55939/tests/kraken/driver.html?testName=json-parse-financial) 16:52:22 INFO - PROCESS | 10792 | RSS: Main: 214761472 16:52:22 INFO - PROCESS | 10792 | 16:52:23 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55939/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:52:24 INFO - PROCESS | 10792 | RSS: Main: 176304128 16:52:24 INFO - PROCESS | 10792 | 16:52:25 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:52:26 INFO - PROCESS | 10792 | RSS: Main: 182370304 16:52:26 INFO - PROCESS | 10792 | 16:52:28 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:52:28 INFO - PROCESS | 10792 | RSS: Main: 189292544 16:52:28 INFO - PROCESS | 10792 | 16:52:31 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:52:32 INFO - PROCESS | 10792 | RSS: Main: 190124032 16:52:32 INFO - PROCESS | 10792 | 16:52:34 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:35 INFO - PROCESS | 10792 | RSS: Main: 193077248 16:52:35 INFO - PROCESS | 10792 | 16:52:36 INFO - PROCESS | 10792 | Cycle 1(1): loaded http://localhost:55939/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55939/tests/kraken/driver.html?testName=ai-astar) 16:52:37 INFO - PROCESS | 10792 | RSS: Main: 243834880 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | __start_tp_report 16:52:37 INFO - PROCESS | 10792 | _x_x_mozilla_page_load 16:52:37 INFO - PROCESS | 10792 | _x_x_mozilla_page_load_details 16:52:37 INFO - PROCESS | 10792 | |i|pagename|runs| 16:52:37 INFO - PROCESS | 10792 | |0;ai-astar;110;111;120;110;110;111;110;110;110;111 16:52:37 INFO - PROCESS | 10792 | |1;audio-beat-detection;146;123;147;178;115;153;145;117;193;121 16:52:37 INFO - PROCESS | 10792 | |2;audio-dft;161;161;160;160;163;161;160;161;161;160 16:52:37 INFO - PROCESS | 10792 | |3;audio-fft;74;75;74;185;74;75;73;84;166;74 16:52:37 INFO - PROCESS | 10792 | |4;audio-oscillator;83;83;163;83;82;84;149;83;82;148 16:52:37 INFO - PROCESS | 10792 | |5;imaging-gaussian-blur;118;117;116;116;116;117;117;117;117;117 16:52:37 INFO - PROCESS | 10792 | |6;imaging-darkroom;146;146;146;145;146;146;146;146;145;145 16:52:37 INFO - PROCESS | 10792 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 16:52:37 INFO - PROCESS | 10792 | |8;json-parse-financial;50;50;50;49;59;50;50;50;51;50 16:52:37 INFO - PROCESS | 10792 | |9;json-stringify-tinderbox;59;61;61;59;61;59;61;59;60;59 16:52:37 INFO - PROCESS | 10792 | |10;stanford-crypto-aes;74;73;72;74;72;73;72;73;73;72 16:52:37 INFO - PROCESS | 10792 | |11;stanford-crypto-ccm;122;124;122;125;118;127;117;119;156;122 16:52:37 INFO - PROCESS | 10792 | |12;stanford-crypto-pbkdf2;151;152;153;155;160;153;158;153;154;153 16:52:37 INFO - PROCESS | 10792 | |13;stanford-crypto-sha256-iterative;63;61;63;61;61;61;59;60;60;60 16:52:37 INFO - PROCESS | 10792 | __end_tp_report 16:52:37 INFO - PROCESS | 10792 | __start_cc_report 16:52:37 INFO - PROCESS | 10792 | _x_x_mozilla_cycle_collect,1657 16:52:37 INFO - PROCESS | 10792 | __end_cc_report 16:52:37 INFO - PROCESS | 10792 | __startTimestamp1476143557242__endTimestamp 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | ------- Summary: start ------- 16:52:37 INFO - PROCESS | 10792 | Number of tests: 14 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#0] ai-astar Cycles:10 Average:111.30 Median:110.50 stddev:3.09 (2.8%) stddev-sans-first:3.24 16:52:37 INFO - PROCESS | 10792 | Values: 110.0 111.0 120.0 110.0 110.0 111.0 110.0 110.0 110.0 111.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:146.50 stddev:26.23 (17.9%) stddev-sans-first:27.81 16:52:37 INFO - PROCESS | 10792 | Values: 146.0 123.0 147.0 178.0 115.0 153.0 145.0 117.0 193.0 121.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 16:52:37 INFO - PROCESS | 10792 | Values: 161.0 161.0 160.0 160.0 163.0 161.0 160.0 161.0 161.0 160.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#3] audio-fft Cycles:10 Average:95.40 Median:74.00 stddev:42.57 (57.5%) stddev-sans-first:44.44 16:52:37 INFO - PROCESS | 10792 | Values: 74.0 75.0 74.0 185.0 74.0 75.0 73.0 84.0 166.0 74.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:83.00 stddev:34.28 (41.3%) stddev-sans-first:35.50 16:52:37 INFO - PROCESS | 10792 | Values: 83.0 83.0 163.0 83.0 82.0 84.0 149.0 83.0 82.0 148.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 16:52:37 INFO - PROCESS | 10792 | Values: 118.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:52:37 INFO - PROCESS | 10792 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:52:37 INFO - PROCESS | 10792 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:50.00 stddev:2.88 (5.8%) stddev-sans-first:3.04 16:52:37 INFO - PROCESS | 10792 | Values: 50.0 50.0 50.0 49.0 59.0 50.0 50.0 50.0 51.0 50.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.50 stddev:0.99 (1.6%) stddev-sans-first:1.00 16:52:37 INFO - PROCESS | 10792 | Values: 59.0 61.0 61.0 59.0 61.0 59.0 61.0 59.0 60.0 59.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:73.00 stddev:0.79 (1.1%) stddev-sans-first:0.71 16:52:37 INFO - PROCESS | 10792 | Values: 74.0 73.0 72.0 74.0 72.0 73.0 72.0 73.0 73.0 72.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:123.00 stddev:11.26 (9.2%) stddev-sans-first:11.89 16:52:37 INFO - PROCESS | 10792 | Values: 122.0 124.0 122.0 125.0 118.0 127.0 117.0 119.0 156.0 122.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:153.50 stddev:2.78 (1.8%) stddev-sans-first:2.70 16:52:37 INFO - PROCESS | 10792 | Values: 151.0 152.0 153.0 155.0 160.0 153.0 158.0 153.0 154.0 153.0 16:52:37 INFO - PROCESS | 10792 | 16:52:37 INFO - PROCESS | 10792 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.29 (2.1%) stddev-sans-first:1.12 16:52:37 INFO - PROCESS | 10792 | Values: 63.0 61.0 63.0 61.0 61.0 61.0 59.0 60.0 60.0 60.0 16:52:37 INFO - PROCESS | 10792 | -------- Summary: end -------- 16:52:37 INFO - PROCESS | 10792 | 16:52:38 INFO - TEST-INFO | 10792: exit 0 16:52:38 INFO - TEST-OK | kraken | took 59826ms 16:52:38 INFO - SUITE-END | took 1172s 16:52:38 INFO - Completed test suite (00:19:32) 16:52:38 INFO - javascript benchmark 16:52:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.398491279113, 3262.119227808491, 3282.0, 3295.6869097435765, 3322.46840505519, 5586.497215250627, 5622.437756224381, 5644.266245388104, 5647.717614119299, 5655.236543066685, 5618.494335509803, 5676.403977582357, 5677.006523858318, 5684.346300175481, 5684.54523638108, 5099.949000510003, 5115.283860259567, 5126.256692779545, 5190.974045129769, 5203.635745497817, 5093.363329583802, 5106.106431374513, 5209.765560549784, 5216.347956505437, 5231.503007214308, 5064.620153488485, 5077.2637967494875, 5098.847034588968, 5129.615278854101, 5134.512221338967, 5131.179011358184, 5239.816606418795, 5245.895082098375, 5247.973760131213, 5277.076511610491, 4985.227289770091, 5067.366579177603, 5142.100132476801, 5174.0, 5202.687838729688, 5466.835994920159, 5499.587530935196, 5499.7250137492965, 5513.751881165357, 5519.613627046108, 5989.341172471025, 6014.609050411709, 6017.157597936249, 6028.577999540034, 6032.034874420072, 5880.382559831241, 5963.13534537494, 6077.24034495688, 6090.0, 6093.0, 5588.105903055492, 5615.438456154352, 5622.381538030813, 5640.041192997201, 5648.435156484319, 6138.877222455615, 6139.785107521216, 6140.201773769381, 6144.047672610753, 6155.96922015396, 5812.070068788974, 5821.7380217890295, 5875.441833025856, 5875.971704951702, 5889.152400502175, 6006.039033754579, 6016.969915150484, 6031.366706495754, 6057.091436284504, 6066.181065556182, 5974.402559744078, 5987.760489580368, 5989.0, 5997.100434934795, 6000.489958353589, 5826.184334193216, 5874.1776151338845, 5883.470487656046, 5918.644881307136, 5993.130996005555, 4608.5621865922685, 4612.423447069116, 4614.400127983341, 4615.723056616614, 4616.376789133491, 4023.1752490739327, 4090.9568060144475, 4102.3026085564925, 4105.4662893823615, 4142.813573389205, 5770.163326317745, 5788.0, 5788.971055144697, 5789.131630255411, 5796.536277097822, 6080.817575472743, 6084.939150608526, 6088.6042407243385, 6092.421219984094, 6096.451319381277, 5627.155926610959, 5729.71351432435, 5772.0, 5777.768889244468, 5805.506531944832, 5309.946900531023, 5319.308489896289, 5337.572994160458, 5345.919811202835, 5350.4649535046965, 6355.491560675135, 6393.520485963571, 6399.296077431479, 6402.551821372553, 6412.422881940554, 5954.851128721816, 6025.0, 6134.8159555213415, 6143.907841382383, 6147.969260153671, 6246.063090536274, 6339.619622822645, 6373.107764913008, 6386.584871983307, 6391.58454700443, 5989.341172470938, 5995.880082398415, 5997.580169388101, 6002.339742628395, 6042.425969532887, 5934.495567876606, 6053.606515576474, 6078.179445774941, 6086.482648974887, 6101.084837274444, 5907.232059832194, 5983.042713165873, 5984.371640977634, 5998.310194327708, 6008.849778755566, 5902.61632993854, 5938.257717785277, 5941.702914854153, 5956.106584012433, 5964.493018093511, 5730.111832665943, 5793.739281732248, 5924.377940316204, 5929.762809487745, 5945.346011938596, 5867.882642347215, 5975.462208401264, 5980.372060933713, 6012.398760123952, 6017.879642407215, 5800.8259752207505, 5814.825555233349, 5818.0, 5822.82531524055, 5825.970870145622], "value": 5578.603461139022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.2756383685334, 655.4363247607057, 658.9505787065968, 667.5494041521971, 674.6424395070615, 34872.3025539489, 47032.294515582296, 47967.28049079258, 48057.0, 51371.0, 47555.76222118893, 47914.281285780744, 48137.51862481374, 48266.51733482664, 48287.517124828744, 8672.826543469128, 9380.108889655488, 9538.0, 9543.33196676233, 9547.427154370725, 10986.945065274662, 11287.435628218598, 11301.4914328855, 11622.593209237679, 11628.709282267968, 11453.083753299716, 11575.594854180106, 11578.363190024545, 11579.247348922294, 11585.073194144448, 11403.258788178784, 11463.713407164803, 11475.196736228467, 11481.0, 11483.712907177303, 11236.550537978472, 11247.887521124765, 11299.943500282528, 11305.660830175108, 11313.886861131366, 45462.31806522905, 45811.77094114541, 45857.0, 45950.77024614889, 45964.77017614924, 3820.6370394812448, 3859.150986782903, 3864.5555761087553, 3931.567527571965, 3947.407888816672, 34874.47688284678, 35070.298594027976, 35458.64541354579, 36756.0, 36834.447483287775, 4707.599854012413, 4808.230683090689, 4832.806687732486, 4839.879003024917, 4860.295257187723, 395411.0, 400156.0, 400748.0, 401038.0, 401740.0, 64977.0, 65026.0, 65377.0, 65429.345706543274, 65472.69054618976, 68889.0, 69050.51171673826, 69069.0, 69193.65403173052, 69213.30786692169, 17155.313787448362, 17374.0, 17388.217530211172, 17391.65216695659, 17397.304107835796, 12507.562235321902, 12635.810462843065, 12848.743025139445, 13047.739045219043, 13155.671108222372, 19376.977848101043, 19421.2231510738, 19502.609947800964, 19513.219471220993, 19530.0, 6563.967180164069, 6691.130153080068, 6740.202815831473, 6807.557508762014, 6866.965665171742, 935.6210734652477, 1011.8279892418307, 1094.2504384496644, 1149.4540093455541, 1178.5168081086886, 34639.30721385558, 34707.4793878092, 34813.0, 35196.47205291923, 35243.823780881445, 1135.4209353229912, 1135.784201001807, 1156.8843115688533, 1209.8608660004213, 1215.3680086355046, 18085.0, 18415.171317290755, 18448.81551184498, 18531.90734046321, 18555.0, 555252.0, 620718.0, 631496.0, 631716.0, 889578.0, 65346.34653653498, 65902.0, 65936.34063659397, 65981.0, 66040.6697966507, 1334.8464926533572, 1434.1825159659102, 1458.7009663019055, 1474.4913005013468, 1474.8009018782327, 32353.676463235068, 34343.82828085843, 37005.0, 37031.0, 37277.6272237285, 57903.71048144732, 58859.411405887106, 58935.0, 59049.0, 59098.704506477196, 515777.0, 521205.0, 522097.0, 522905.0, 525230.0, 38815.417768734056, 40911.1817763649, 48146.27780583224, 49864.0, 50121.0, 47172.528274718185, 52328.73835630798, 52661.10363930021, 52802.73598631982, 53160.73419632877, 43606.78196608997, 44564.77717611521, 44629.77685111554, 46228.306575400726, 46258.30612540882, 44418.33372499351, 45698.543014569426, 45730.0, 45757.0, 46302.536974629824, 44438.0, 44763.10473790441, 45876.0, 45940.77029614831, 48295.5170448291, 38401.42397863978, 39813.0, 40313.1937361257, 40820.79589602152, 41105.1778964413, 29907.850460747555, 29934.70065299319, 30782.384352313275, 30861.382772344878, 31079.0], "value": 22595.368351586138, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.3493236160337, 398.94614227079336, 418.1615860200298, 419.17841031578104, 426.0095278477539, 19114.522136946583, 20290.69563956538, 20672.586548269024, 20916.581668366623, 20949.266775662854, 19787.901060494678, 19803.70294445581, 19963.700544491843, 20709.275175368864, 20856.68714969273, 5457.372402173752, 5647.37878833328, 5654.264945557072, 5655.434456554352, 5658.122990936391, 5857.3849745776715, 5883.205767221413, 5889.087191485305, 5921.555883308748, 5941.88116237675, 5807.332156801979, 5897.262842144732, 5897.528197744192, 5939.613925094855, 5949.583529152961, 5813.796517121901, 5844.444777746107, 5849.385814489502, 5862.413758624146, 5931.703414829241, 5248.5538729208065, 5289.18017707256, 5289.894202115975, 5294.92057619136, 5297.0, 24196.2741117765, 24715.752842471524, 24771.0, 24974.1259055934, 25007.249782506555, 2616.149751330806, 2640.854752988585, 2706.201670507205, 3034.833084180369, 3234.175285302233, 15163.783950065941, 15379.231038448144, 15396.153211573359, 15674.294656740474, 15859.762103568455, 2010.2643363848933, 2059.1458908831264, 2060.5504770069588, 2068.1410639569576, 2084.4163634182455, 80324.59837700774, 86023.0, 88071.0, 88246.55876720576, 90027.54986225092, 11235.157363197794, 11774.411279435993, 11871.347075910735, 11877.406129693481, 11924.88075119255, 16912.57718557046, 16917.154142292835, 16944.745828812578, 16994.745078823827, 17036.574085647957, 8082.7171049013, 8419.621117049748, 8440.53577053268, 8647.956760216159, 8656.8701469478, 8079.5960201989665, 8094.473859199251, 8105.918940810517, 8258.298044666151, 8278.172182781673, 4847.88891868537, 4954.430240522386, 5143.6399452037995, 5148.17629179337, 5183.585313174937, 1895.5545446819986, 1915.2721965652963, 1922.2695375757123, 1938.8351990081, 1983.335582579823, 909.0, 995.571355105426, 1003.3779056984716, 1042.713253855196, 1084.143526613982, 19218.519537011405, 20811.58376832485, 20987.685184722242, 21094.156233750786, 21176.047077881532, 991.4249735153594, 1064.2922456566296, 1068.371805358826, 1096.9012788849043, 1153.6827372472637, 11019.173561982883, 11483.483243254073, 11593.420328983517, 11702.531898724126, 11852.762944741056, 413464.0, 414330.0, 422285.0, 517661.0, 526225.0, 89798.0, 98316.0, 101343.0, 101682.0, 102237.48881255546, 1051.0803047333584, 1083.664064140119, 1090.9945450272699, 1116.843641890136, 1146.9369184694922, 16397.91801040987, 18675.626487470447, 19114.0, 19136.23455061782, 19423.0, 43601.56398435975, 44561.55438445574, 44669.65991020275, 44895.55104448914, 45681.0, 63127.6843615779, 63434.365656342845, 63471.6826415865, 63541.0, 64511.67744161249, 8604.526751028503, 8740.781480462785, 8983.595738191667, 9046.321525885453, 9189.127032931861, 10097.949510252402, 12959.0, 13620.65948351299, 13959.302034898417, 14203.28983550839, 11294.152938529478, 11510.136739744385, 11547.8267825981, 11688.70778230551, 11929.719327185094, 11280.379579123228, 11316.0, 11350.545978161112, 11550.88449115498, 11942.581830824738, 11227.71930701739, 11332.320060796379, 11430.942845285721, 11476.368799716096, 11752.529898804294, 8720.563971801512, 8816.51509167002, 8867.911320886966, 8907.242884354902, 9108.681196158102, 9598.42409455435, 10453.634122805664, 11568.537258509565, 11798.174127811315, 11865.110116741107], "value": 9683.266726999913, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.3969173409562, 1526.6641338905438, 1560.4148444333373, 1676.9580760480987, 1800.684880145975, 8415.579221038946, 9345.626174953002, 9698.418094914301, 9700.514974251277, 9976.551055202515, 9641.56612952417, 9675.0, 9732.221422286219, 9733.367331123472, 9756.219502439806, 2413.2157048959116, 2461.90152393904, 2463.8521688698734, 2469.469064151207, 2490.6762120924254, 2423.8060955123638, 2526.2295000024983, 2530.8481491110592, 2531.3671582104475, 2540.9110681126162, 2429.9514009719796, 2443.30365845734, 2454.337328921199, 2458.7172475165316, 2458.9508209835794, 2354.3290162303833, 2379.892904819287, 2379.9885048854253, 2409.71083469983, 2422.4307287787356, 2318.2349824557855, 2367.9644805327935, 2381.404648837791, 2387.904483820645, 2387.9880600597057, 9540.33217674763, 9652.469114198717, 9675.129238368581, 9745.02549745027, 9753.170980466668, 5498.890022199574, 5683.0, 5714.742836572323, 5718.885622287573, 5724.914126288109, 3466.254755227613, 3480.2865412590613, 3498.4577390504514, 3539.7699149555456, 3542.8051457169845, 1119.4794420594462, 1123.1127409346607, 1137.9715507112305, 1147.8909503597145, 1149.367847683779, 13832.515861944885, 13956.0, 13958.0, 13965.092269002482, 13979.440822367093, 4598.0803839232285, 4623.468301145412, 4794.352762377034, 4803.5916947059195, 4816.470188279286, 10857.185711071701, 10864.402457864893, 10880.183986201067, 10882.836757448644, 10882.891171088346, 5340.679559226382, 5362.812301569505, 5378.91931621026, 5387.865303367447, 5400.378956419983, 4650.558196971282, 4991.50084991498, 5065.544101030925, 5134.614903882224, 5196.45437229093, 3959.2279505496153, 4081.0, 4094.61101195386, 4111.9177616447505, 4206.242876282237, 1169.8128299472044, 1182.8225766134976, 1197.8382918305917, 1198.161287099034, 1219.7560487902279, 1032.4992378696327, 1036.2797855490362, 1058.5871510111062, 1059.3167407022427, 1068.150820098024, 6399.488040956712, 6500.187476565429, 6551.148350714377, 6604.0, 6691.096701945273, 973.5229737428609, 985.9654912078042, 1002.4336250018716, 1020.6682828080903, 1058.7353161709573, 4435.268180750205, 4575.5424457554645, 4629.213033784265, 4672.112298663243, 4686.343911852344, 11989.0, 11990.820137697941, 12339.0, 12480.313582752855, 12540.059495537871, 12413.75172496545, 12429.564965226175, 12446.315452650191, 12461.688457788447, 12465.93767031159, 8673.826523469621, 9161.312901532412, 9213.12475314844, 9218.400803947721, 9254.074592540826, 6599.769008084694, 6605.5376123670885, 6623.0, 6628.867422651617, 6712.295209002984, 11442.084633229155, 11468.541258349906, 11514.596989105341, 11542.538298467967, 11742.0, 11370.886291137314, 11442.885571144516, 11678.124140689652, 11838.348890810743, 11947.462364193805, 7448.73929412468, 7942.166072562298, 8148.348132149295, 8310.750677479686, 8443.831123377375, 9664.71005869825, 9706.514674266116, 9747.805043899225, 9748.80502389934, 9774.609015639295, 8119.228673276029, 8355.248027677539, 8576.44253123545, 8653.740387788377, 8672.739817805475, 8022.358211342962, 8130.065042520186, 8206.671733130608, 8431.831363372821, 8513.40406171562, 7318.121825380988, 7358.080239970004, 7415.147258065392, 7421.628918554159, 7441.367483763941, 5592.692401917933, 5620.915686264628, 5660.971695141498, 5724.4561766632105, 5800.332961709458, 5687.77248910039, 5771.076627739541, 5841.18223448709, 5881.588288819741, 5904.114382842608], "value": 4990.849265950594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.71554609673973, 347.6002597013435, 348.5190437196668, 353.4239190120104, 358.3120408815073, 5383.946160538393, 5407.324084489439, 5416.052190866598, 5438.700871452069, 5446.1558458439, 5324.334458192726, 5348.277982472375, 5391.353037635479, 5408.376937185462, 5437.184422336652, 4173.4157217989505, 4208.516020657617, 4250.214909737918, 4257.212415703089, 4261.40054566714, 4237.703360764748, 4245.660347172235, 4250.468691413574, 4266.957330426702, 4303.289957157065, 4202.621764041236, 4227.408162857202, 4256.659467242629, 4258.510271318792, 4283.785810709452, 4045.534763502191, 4081.306257902952, 4199.488184253666, 4234.513031001413, 4236.449261596003, 3780.865740277933, 3790.90522736931, 3791.469194312798, 3791.9431208531896, 3798.4682144499793, 5185.066687996159, 5211.296474975906, 5219.164933610642, 5259.894802103937, 5299.311089558333, 4706.858794236178, 4722.693024953402, 4792.712437253741, 4823.13183626947, 4830.975845120752, 5496.148097044965, 5518.53092487135, 5529.502344788948, 5534.533348662608, 5534.592452283229, 4108.50697916249, 4145.606167414091, 4155.439015732898, 4165.58344165581, 4174.248635245654, 5701.0878259478695, 5742.626729262584, 5756.956280520397, 5759.971200144015, 5769.365369809276, 4246.787660616957, 4273.914521709548, 4275.615194632436, 4281.993731473101, 4290.0, 4341.435613370305, 4348.173846969065, 4349.869503914888, 4370.125974805052, 4380.70769123103, 4715.339852420664, 4757.738324392122, 4767.547083027107, 4799.952000480021, 4938.506149385104, 5539.639923404966, 5548.334199896039, 5574.554035677217, 5584.301962254718, 5596.321103668883, 1487.7056960444265, 1497.1466264229284, 1500.6848561802144, 1504.277946585623, 1504.353128154904, 919.4896832258161, 971.6939164163407, 978.0122076702439, 979.2802290316612, 992.8014397120605, 5410.729463526808, 5438.320209973754, 5441.918371224356, 5446.945530544644, 5455.0, 5669.06460434032, 5678.176664383641, 5678.943210567842, 5701.947298972366, 5710.2862142232225, 5333.359996800409, 5442.537384322377, 5444.265024221759, 5452.982396513236, 5456.972715136399, 3004.684508126659, 3019.3659331540184, 3019.4112148131344, 3030.7423868970945, 3030.818150910952, 5758.942410575924, 5926.2295901532525, 5944.375840536768, 5956.5532585055425, 5979.820605381846, 5890.087036509348, 5940.673262970492, 5953.880922381528, 5956.851078723066, 5960.6721630309885, 6026.890588293974, 6079.604825686316, 6091.939080609226, 6092.908606370908, 6168.784092556739, 5578.888422231614, 5778.94221057784, 5807.0998995155815, 5815.505682016907, 5865.560082993878, 5733.971330143323, 5746.224259724883, 5747.252857128547, 5752.9712351437975, 5764.827055188351, 5847.970760146172, 6061.818145455643, 6074.3014553325165, 6074.544409169418, 6128.264608247039, 4137.37939309094, 4176.519700234512, 4177.352510360899, 4190.769507676985, 4222.176675548239, 5884.205632239764, 5888.411158884077, 5889.911651325147, 5903.586748927534, 5903.586748927534, 5543.584231182758, 5687.488126068673, 5688.175214593778, 5693.914591281217, 5722.713864306851, 5689.943100568941, 5696.629719068247, 5698.31620205578, 5718.342390625131, 5722.570807189394, 5376.0, 5587.273654424899, 5664.801731939528, 5698.1737648041635, 5735.426457354231, 5282.31329927107, 5687.829365119053, 5689.857753556194, 5691.715414229355, 5701.7149142541875, 5526.530244929065, 5543.473370029841, 5551.19586134516, 5552.888942221214, 5558.166275058774], "value": 4339.613144275973, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [790.1150711203456, 821.1501096365259, 839.9034111077226, 847.5677404523691, 896.6592694775986, 14775.0, 16298.837011629896, 17484.38804641838, 17623.0, 17724.37964671235, 15965.760513592306, 16059.277332520036, 16114.0, 16119.113448760314, 16123.516294511184, 836.991630083699, 857.3184318466834, 861.6036623153357, 865.7229686500323, 868.956552172392, 864.2826454043129, 865.9913400865973, 866.0386970462781, 866.5797088412147, 866.9089745576719, 895.4448242089913, 900.5902314446911, 902.9458232506038, 905.0316161706976, 905.9682911098113, 892.8973168085623, 895.6283142495856, 900.0414558495214, 900.3562452846213, 900.6127365232948, 663.7477758451757, 664.8271449423137, 665.8435267712084, 665.893457046875, 666.1273731411866, 16328.0, 16361.100139492322, 16423.589410264718, 16447.342106315733, 16453.670926581402, 13960.58118256454, 14038.438462461489, 14053.437862485489, 14201.857981420157, 15712.45006424781, 14011.509597164051, 14107.576772696835, 14156.433742650283, 14212.360443780055, 14371.425142994267, 4743.549362810528, 4764.475907650156, 4800.735959522225, 4843.4914333994775, 4857.805687772485, 58089.0, 71575.28424715738, 74143.62928185379, 74230.62884685597, 87309.56345218234, 11592.942035289938, 11813.586524471602, 11891.702707432383, 11907.642770716893, 11976.0, 18981.905090474735, 18994.240230390908, 19006.80993190078, 19250.422487325402, 19666.311679091163, 8589.26991205742, 8894.510801905957, 8922.643094276285, 8984.955075224712, 9055.36612437123, 4854.538818812207, 4992.605207726478, 5048.1418158913075, 5101.744912754348, 5206.193040078794, 3820.4078367853026, 3960.0, 3961.3661814109605, 3965.841366345314, 3974.582668819732, 692.342274838905, 692.5982929900646, 700.1388292400376, 701.3897908819258, 705.0763499815258, 777.6461709921972, 788.4402074527035, 825.2078005115037, 842.0905422144192, 849.2653854415886, 10033.046860548382, 10066.496675166212, 10215.387076775418, 10295.227857910686, 10532.36805791655, 696.7392602619788, 730.6967608442543, 734.4565021883874, 748.3377211168155, 759.6809340077181, 4738.763061846894, 4741.146593613183, 4872.805087796449, 4973.626977976666, 4986.177280748636, 75338.0, 106490.93509064965, 130187.0, 131729.0, 135046.0, 71838.9224161638, 87467.1253287459, 89699.72101952932, 115123.0, 115270.0, 86573.0, 87629.37997327637, 87856.560717196, 90982.54508727413, 91722.0, 9628.518574071128, 9668.758281042748, 9777.462239576891, 10434.0, 10623.309484883457, 39837.40243896402, 50108.24837627366, 51091.2336314963, 52208.47791522036, 52391.0, 69173.65413172902, 70054.29945700477, 73714.63142684252, 77157.228427715, 78853.0, 13352.0, 13598.524051658145, 13950.372233249731, 14283.500077497238, 14432.989690721548, 15220.543383698507, 15452.768208477106, 15585.76621350658, 15678.216089195266, 16157.353705852105, 14688.97177197586, 14728.926355368154, 14730.26348682583, 14746.926265368604, 14805.925970370079, 14657.92671036638, 14712.337944792296, 14765.926170369079, 15038.924805375904, 15659.608509787346, 14281.928590357398, 15004.849951500346, 15034.473793417616, 15386.538403847902, 15850.68298634044, 11604.187706860439, 11630.0, 12222.0, 12357.38213089324, 12483.500659973499, 10971.725706857393, 11488.138389620644, 11535.5962541314, 12111.0, 13225.0], "value": 8005.847271031786, "unit": "score"}], "name": "dromaeo_css", "value": 7719.826655065748, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 147.0, 178.0, 115.0, 153.0, 145.0, 117.0, 193.0, 121.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 163.0, 161.0, 160.0, 161.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 185.0, 74.0, 75.0, 73.0, 84.0, 166.0, 74.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 163.0, 83.0, 82.0, 84.0, 149.0, 83.0, 82.0, 148.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 49.0, 59.0, 50.0, 50.0, 50.0, 51.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 59.0, 61.0, 59.0, 61.0, 59.0, 60.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 74.0, 72.0, 73.0, 72.0, 73.0, 73.0, 72.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 124.0, 122.0, 125.0, 118.0, 127.0, 117.0, 119.0, 156.0, 122.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 153.0, 155.0, 160.0, 153.0, 158.0, 153.0, 154.0, 153.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 63.0, 61.0, 61.0, 61.0, 59.0, 60.0, 60.0, 60.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1496.6000000000001, "alertThreshold": 2.0}]} 16:52:38 INFO - Return code: 0 16:52:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:52:38 INFO - # TBPL SUCCESS # 16:52:38 INFO - Running post-action listener: _resource_record_post_action 16:52:38 INFO - [mozharness: 2016-10-10 23:52:38.573457Z] Finished run-tests step (success) 16:52:38 INFO - Running post-run listener: _resource_record_post_run 16:52:38 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290594816; Read time: 8; Write time: 125468 16:52:38 INFO - TinderboxPrint: CPU usage
12.9% 16:52:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:52:38 INFO - TinderboxPrint: I/O write bytes / time
290,594,816 / 125,468 16:52:38 INFO - TinderboxPrint: CPU idle
8,254.1 (87.1%) 16:52:38 INFO - TinderboxPrint: CPU user
1,178.3 (12.4%) 16:52:38 INFO - TinderboxPrint: Swap in / out
0 / 0 16:52:38 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70103040; Read time: 0; Write time: 28964 16:52:38 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 219938816; Read time: 0; Write time: 96432 16:52:39 INFO - Running post-run listener: _upload_blobber_files 16:52:39 INFO - Blob upload gear active. 16:52:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:52:39 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 16:52:39 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'] 16:52:39 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 16:52:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:52:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:52:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:40 INFO - (blobuploader) - INFO - Done attempting. 16:52:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:46 INFO - (blobuploader) - INFO - Done attempting. 16:52:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:52:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:46 INFO - (blobuploader) - INFO - Done attempting. 16:52:46 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:46 INFO - Return code: 0 16:52:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc0dd57fda2ab5309b2fc92eaae4cad3a62dcb78fbbe312670f9da8d614285c8448ab85de66c504c0bf9f6a4a70aff7a3d7090ac26f4e185e7875159a5745cdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11ba6776873611e83f030a7b54debcdce7a48b99a8caf79c0c2c8699a97f913f74aa1ce9b26e95ca9fd9923af641a62c58cc938a6419f2315cb11f4abe03d006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5955191afc9f1bd54e2948ae18c7168857b5413da4648aa5bb4a4f76dd2e0b524feeff76228ec04ffee0836af02ad04a19986d681e52e782471f2d23880546ec"} 16:52:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:46 INFO - Contents: 16:52:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc0dd57fda2ab5309b2fc92eaae4cad3a62dcb78fbbe312670f9da8d614285c8448ab85de66c504c0bf9f6a4a70aff7a3d7090ac26f4e185e7875159a5745cdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11ba6776873611e83f030a7b54debcdce7a48b99a8caf79c0c2c8699a97f913f74aa1ce9b26e95ca9fd9923af641a62c58cc938a6419f2315cb11f4abe03d006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5955191afc9f1bd54e2948ae18c7168857b5413da4648aa5bb4a4f76dd2e0b524feeff76228ec04ffee0836af02ad04a19986d681e52e782471f2d23880546ec"} 16:52:46 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:46 INFO - Copying logs to upload dir... 16:52:46 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.692544 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-10-10 16:52:46.445211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 16:52:46.449156) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc0dd57fda2ab5309b2fc92eaae4cad3a62dcb78fbbe312670f9da8d614285c8448ab85de66c504c0bf9f6a4a70aff7a3d7090ac26f4e185e7875159a5745cdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11ba6776873611e83f030a7b54debcdce7a48b99a8caf79c0c2c8699a97f913f74aa1ce9b26e95ca9fd9923af641a62c58cc938a6419f2315cb11f4abe03d006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5955191afc9f1bd54e2948ae18c7168857b5413da4648aa5bb4a4f76dd2e0b524feeff76228ec04ffee0836af02ad04a19986d681e52e782471f2d23880546ec"} build_url:https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011459 build_url: 'https://queue.taskcluster.net/v1/task/BXAOkFL2TKar9ROcGcXOtw/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cc0dd57fda2ab5309b2fc92eaae4cad3a62dcb78fbbe312670f9da8d614285c8448ab85de66c504c0bf9f6a4a70aff7a3d7090ac26f4e185e7875159a5745cdd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11ba6776873611e83f030a7b54debcdce7a48b99a8caf79c0c2c8699a97f913f74aa1ce9b26e95ca9fd9923af641a62c58cc938a6419f2315cb11f4abe03d006", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5955191afc9f1bd54e2948ae18c7168857b5413da4648aa5bb4a4f76dd2e0b524feeff76228ec04ffee0836af02ad04a19986d681e52e782471f2d23880546ec"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 16:52:46.474900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:52:46.475259) ========= 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=31acecf42b6a79ec49471d4a000001bb-1476141887.867947-346264335 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 16:52:46.557850) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 16:52:46.558211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 16:52:46.558545) ========= ========= Total master_lag: 0.17 =========