builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1475690646.12 results: success (0) buildid: 20161005080800 builduid: 73a7db6c0aac495dbb2af125db887b24 revision: dacddb224d32cd5f979fa4b53e702c7dc69be46c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.123448) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.123935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.124267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.139039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.139362) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-05 11:04:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-10-05 11:04:06 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105827 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.259630) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.260034) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016800 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:06.310213) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 11:04:06.310622) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev dacddb224d32cd5f979fa4b53e702c7dc69be46c --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 dacddb224d32cd5f979fa4b53e702c7dc69be46c --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=5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-05 11:04:06,500 truncating revision to first 12 chars 2016-10-05 11:04:06,500 Setting DEBUG logging. 2016-10-05 11:04:06,500 attempt 1/10 2016-10-05 11:04:06,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/dacddb224d32?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 11:04:07,530 unpacking tar archive at: mozilla-beta-dacddb224d32/testing/mozharness/ program finished with exit code 0 elapsedTime=1.378117 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 11:04:07.702622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:07.702991) ========= script_repo_revision: dacddb224d32cd5f979fa4b53e702c7dc69be46c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:07.703444) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:07.703750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 11:04:07.714721) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-05 11:04:07.715097) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:04:07 INFO - MultiFileLogger online at 20161005 11:04:07 in /builds/slave/test 11:04:07 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 11:04:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:04:07 INFO - {'append_to_log': False, 11:04:07 INFO - 'base_work_dir': '/builds/slave/test', 11:04:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:04:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:04:07 INFO - 'branch': 'Mozilla-Beta', 11:04:07 INFO - 'buildbot_json_path': 'buildprops.json', 11:04:07 INFO - 'config_files': ('talos/linux_config.py',), 11:04:07 INFO - 'default_actions': ('clobber', 11:04:07 INFO - 'read-buildbot-config', 11:04:07 INFO - 'download-and-extract', 11:04:07 INFO - 'populate-webroot', 11:04:07 INFO - 'create-virtualenv', 11:04:07 INFO - 'install', 11:04:07 INFO - 'run-tests'), 11:04:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:04:07 INFO - 'download_minidump_stackwalk': True, 11:04:07 INFO - 'download_symbols': 'ondemand', 11:04:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:04:07 INFO - 'tooltool.py': '/tools/tooltool.py', 11:04:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:04:07 INFO - '/tools/misc-python/virtualenv.py')}, 11:04:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:04:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:04:07 INFO - 'installer_path': 'installer.exe', 11:04:07 INFO - 'log_level': 'info', 11:04:07 INFO - 'log_name': 'talos', 11:04:07 INFO - 'log_to_console': True, 11:04:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:04:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:04:07 INFO - 'opt_config_files': (), 11:04:07 INFO - 'pip_index': False, 11:04:07 INFO - 'sps_profile': False, 11:04:07 INFO - 'sps_profile_interval': 0, 11:04:07 INFO - 'suite': 'dromaeojs', 11:04:07 INFO - 'system_bits': '32', 11:04:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:04:07 INFO - 'title': 'talos-linux64-ix-051', 11:04:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:04:07 INFO - 'use_talos_json': True, 11:04:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:04:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:04:07 INFO - 'work_dir': 'build'} 11:04:07 INFO - [mozharness: 2016-10-05 18:04:07.998168Z] Running clobber step. 11:04:07 INFO - Running pre-action listener: _resource_record_pre_action 11:04:07 INFO - Running main action method: clobber 11:04:07 INFO - rmtree: /builds/slave/test/build 11:04:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:08 INFO - Running post-action listener: _resource_record_post_action 11:04:08 INFO - [mozharness: 2016-10-05 18:04:08.462259Z] Finished clobber step (success) 11:04:08 INFO - [mozharness: 2016-10-05 18:04:08.462360Z] Running read-buildbot-config step. 11:04:08 INFO - Running pre-action listener: _resource_record_pre_action 11:04:08 INFO - Running main action method: read_buildbot_config 11:04:08 INFO - Using buildbot properties: 11:04:08 INFO - { 11:04:08 INFO - "project": "", 11:04:08 INFO - "product": "firefox", 11:04:08 INFO - "script_repo_revision": "production", 11:04:08 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:04:08 INFO - "repository": "", 11:04:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 11:04:08 INFO - "buildid": "20161005080800", 11:04:08 INFO - "pgo_build": "True", 11:04:08 INFO - "basedir": "/builds/slave/test", 11:04:08 INFO - "buildnumber": 183, 11:04:08 INFO - "slavename": "talos-linux64-ix-051", 11:04:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 11:04:08 INFO - "platform": "ubuntu64_hw", 11:04:08 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 11:04:08 INFO - "branch": "mozilla-beta", 11:04:08 INFO - "repo_path": "releases/mozilla-beta", 11:04:08 INFO - "revision": "dacddb224d32cd5f979fa4b53e702c7dc69be46c", 11:04:08 INFO - "stage_platform": "linux64-pgo", 11:04:08 INFO - "builduid": "73a7db6c0aac495dbb2af125db887b24", 11:04:08 INFO - "slavebuilddir": "test" 11:04:08 INFO - } 11:04:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 11:04:08 INFO - Running post-action listener: _resource_record_post_action 11:04:08 INFO - [mozharness: 2016-10-05 18:04:08.464809Z] Finished read-buildbot-config step (success) 11:04:08 INFO - [mozharness: 2016-10-05 18:04:08.464898Z] Running download-and-extract step. 11:04:08 INFO - Running pre-action listener: _resource_record_pre_action 11:04:08 INFO - Running main action method: download_and_extract 11:04:08 INFO - mkdir: /builds/slave/test/build/tests 11:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:04:08 INFO - https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:04:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:04:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:04:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:04:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:04:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:04:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:04:08 INFO - retry: Failed, sleeping 30 seconds before retrying 11:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:04:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:04:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:04:38 INFO - retry: Failed, sleeping 60 seconds before retrying 11:05:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:05:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:05:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:05:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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! 11:05:38 INFO - Caught exception: HTTP Error 404: Not Found 11:05:38 INFO - Caught exception: HTTP Error 404: Not Found 11:05:38 INFO - Caught exception: HTTP Error 404: Not Found 11:05:38 INFO - trying https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:05:38 INFO - Downloading https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:05:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:05:39 INFO - Downloaded 1327 bytes. 11:05:39 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 11:05:39 INFO - Using the following test package requirements: 11:05:39 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 11:05:39 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 11:05:39 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 11:05:39 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'jsshell-linux-x86_64.zip'], 11:05:39 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 11:05:39 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 11:05:39 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 11:05:39 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 11:05:39 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 11:05:39 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 11:05:39 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 11:05:39 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:05: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')]} 11:05:39 INFO - https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:05:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:05:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:05:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:05:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:05:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:05:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:05:39 INFO - retry: Failed, sleeping 30 seconds before retrying 11:06:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:06:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:06:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:06:09 INFO - retry: Failed, sleeping 60 seconds before retrying 11:07:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:07:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:07:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:07:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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! 11:07:09 INFO - Caught exception: HTTP Error 404: Not Found 11:07:09 INFO - Caught exception: HTTP Error 404: Not Found 11:07:09 INFO - Caught exception: HTTP Error 404: Not Found 11:07:09 INFO - trying https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:07:09 INFO - Downloading https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:07:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:07:10 INFO - Downloaded 21772138 bytes. 11:07:10 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'] 11:07:10 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 11:07:11 INFO - Return code: 0 11:07:11 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 11:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:07:11 INFO - https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:07:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:07:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:07:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:07:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:07:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:07:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:07:11 INFO - retry: Failed, sleeping 30 seconds before retrying 11:07:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:07:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:07:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:07:41 INFO - retry: Failed, sleeping 60 seconds before retrying 11:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:08:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:08:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:08:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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! 11:08:41 INFO - Caught exception: HTTP Error 404: Not Found 11:08:41 INFO - Caught exception: HTTP Error 404: Not Found 11:08:41 INFO - Caught exception: HTTP Error 404: Not Found 11:08:41 INFO - trying https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 11:08:41 INFO - Downloading https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:08:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:08:42 INFO - Downloaded 21772138 bytes. 11:08:42 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'] 11:08:42 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 11:08:43 INFO - Return code: 0 11:08: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')]} 11:08:43 INFO - https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:08:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:08:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:08:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:08:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:08:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:08:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:08:43 INFO - retry: Failed, sleeping 30 seconds before retrying 11:09:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:09:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:09:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:09:13 INFO - retry: Failed, sleeping 60 seconds before retrying 11:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:10:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:10:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:10:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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! 11:10:13 INFO - Caught exception: HTTP Error 404: Not Found 11:10:13 INFO - Caught exception: HTTP Error 404: Not Found 11:10:13 INFO - Caught exception: HTTP Error 404: Not Found 11:10:13 INFO - trying https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 11:10:13 INFO - Downloading https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:10:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:10:14 INFO - Downloaded 14413740 bytes. 11:10:14 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'] 11:10:14 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 11:10:14 INFO - Return code: 0 11:10:14 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')]} 11:10:14 INFO - https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:10:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:10:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:10:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:10:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:10:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:10:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 11:10:14 INFO - retry: Failed, sleeping 30 seconds before retrying 11:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:10:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:10:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 11:10:44 INFO - retry: Failed, sleeping 60 seconds before retrying 11:11:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:11:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:11:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 11:11:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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! 11:11:44 INFO - Caught exception: HTTP Error 404: Not Found 11:11:44 INFO - Caught exception: HTTP Error 404: Not Found 11:11:44 INFO - Caught exception: HTTP Error 404: Not Found 11:11:44 INFO - trying https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:11:44 INFO - Downloading https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:11:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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 11:11:45 INFO - Downloaded 56767662 bytes. 11:11:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:11:45 INFO - mkdir: /builds/slave/test/properties 11:11:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:11:45 INFO - Writing to file /builds/slave/test/properties/build_url 11:11:45 INFO - Contents: 11:11:45 INFO - build_url:https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 11:11:46 INFO - Running post-action listener: _resource_record_post_action 11:11:46 INFO - Running post-action listener: set_extra_try_arguments 11:11:46 INFO - [mozharness: 2016-10-05 18:11:46.562351Z] Finished download-and-extract step (success) 11:11:46 INFO - [mozharness: 2016-10-05 18:11:46.562586Z] Running populate-webroot step. 11:11:46 INFO - Running pre-action listener: _resource_record_pre_action 11:11:46 INFO - Running main action method: populate_webroot 11:11:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:11:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:11:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:11:46 INFO - '--tppagecycles', 11:11:46 INFO - '7'], 11:11:46 INFO - 'tests': ['tsvgm']}}, 11:11:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:11:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:11:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:11:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:11:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['damp', 'tps']}, 11:11:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'tests': ['damp', 'tps']}, 11:11:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['dromaeo_dom']}, 11:11:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:11:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['basic_compositor_video']}, 11:11:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:11:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['a11yr', 11:11:46 INFO - 'ts_paint', 11:11:46 INFO - 'tpaint', 11:11:46 INFO - 'sessionrestore', 11:11:46 INFO - 'sessionrestore_no_auto_restore', 11:11:46 INFO - 'tabpaint']}, 11:11:46 INFO - 'other-e10s': {'tests': ['a11yr', 11:11:46 INFO - 'ts_paint', 11:11:46 INFO - 'tpaint', 11:11:46 INFO - 'sessionrestore', 11:11:46 INFO - 'sessionrestore_no_auto_restore', 11:11:46 INFO - 'tabpaint']}, 11:11:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:11:46 INFO - 'ts_paint', 11:11:46 INFO - 'tpaint', 11:11:46 INFO - 'sessionrestore', 11:11:46 INFO - 'sessionrestore_no_auto_restore', 11:11:46 INFO - 'tabpaint']}, 11:11:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:11:46 INFO - 'ts_paint', 11:11:46 INFO - 'tpaint', 11:11:46 INFO - 'sessionrestore', 11:11:46 INFO - 'sessionrestore_no_auto_restore', 11:11:46 INFO - 'tabpaint']}, 11:11:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['a11yr', 11:11:46 INFO - 'ts_paint', 11:11:46 INFO - 'tpaint', 11:11:46 INFO - 'sessionrestore', 11:11:46 INFO - 'sessionrestore_no_auto_restore', 11:11:46 INFO - 'tabpaint']}, 11:11:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['a11yr', 11:11:46 INFO - 'ts_paint', 11:11:46 INFO - 'tpaint', 11:11:46 INFO - 'sessionrestore', 11:11:46 INFO - 'sessionrestore_no_auto_restore', 11:11:46 INFO - 'tabpaint']}, 11:11:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['tsvgx', 11:11:46 INFO - 'tsvgr_opacity', 11:11:46 INFO - 'tart', 11:11:46 INFO - 'tscrollx', 11:11:46 INFO - 'cart']}, 11:11:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:11:46 INFO - 'tsvgr_opacity', 11:11:46 INFO - 'tart', 11:11:46 INFO - 'tscrollx', 11:11:46 INFO - 'cart']}, 11:11:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'talos_options': ['--disable-e10s'], 11:11:46 INFO - 'tests': ['tp5o']}, 11:11:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'tests': ['tp5o']}, 11:11:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'talos_options': ['--disable-e10s', 11:11:46 INFO - '--xperf_path', 11:11:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:46 INFO - 'tests': ['tp5n']}, 11:11:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:11:46 INFO - 'talos_options': ['--xperf_path', 11:11:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:46 INFO - 'tests': ['tp5n']}}, 11:11:46 INFO - 'talos.zip': {'path': '', 11:11:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:11:46 INFO - Running post-action listener: _resource_record_post_action 11:11:46 INFO - [mozharness: 2016-10-05 18:11:46.583082Z] Finished populate-webroot step (success) 11:11:46 INFO - [mozharness: 2016-10-05 18:11:46.583171Z] Running create-virtualenv step. 11:11:46 INFO - Running pre-action listener: _resource_record_pre_action 11:11:46 INFO - Running main action method: create_virtualenv 11:11:46 INFO - Creating virtualenv /builds/slave/test/build/venv 11:11:46 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 11:11:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:11:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:11:46 INFO - Using real prefix '/usr' 11:11:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:11:47 INFO - Installing distribute.............................................................................................................................................................................................done. 11:11:49 INFO - Installing pip.................done. 11:11:49 INFO - Return code: 0 11:11:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:11:49 INFO - Using env: {'DISPLAY': ':0', 11:11:49 INFO - 'HOME': '/home/cltbld', 11:11:49 INFO - 'LANG': 'en_US.UTF-8', 11:11:49 INFO - 'LANGUAGE': 'en_US:en', 11:11:49 INFO - 'LOGNAME': 'cltbld', 11:11:49 INFO - 'MAIL': '/var/mail/cltbld', 11:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:49 INFO - 'MOZ_NO_REMOTE': '1', 11:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:49 INFO - 'NO_EM_RESTART': '1', 11:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:49 INFO - 'PWD': '/builds/slave/test', 11:11:49 INFO - 'SHELL': '/bin/bash', 11:11:49 INFO - 'SHLVL': '1', 11:11:49 INFO - 'TERM': 'linux', 11:11:49 INFO - 'TMOUT': '86400', 11:11:49 INFO - 'USER': 'cltbld', 11:11:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:49 INFO - '_': '/tools/buildbot/bin/python'} 11:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:49 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 11:11:49 INFO - Cleaning up... 11:11:49 INFO - Return code: 0 11:11:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:11:49 INFO - Using env: {'DISPLAY': ':0', 11:11:49 INFO - 'HOME': '/home/cltbld', 11:11:49 INFO - 'LANG': 'en_US.UTF-8', 11:11:49 INFO - 'LANGUAGE': 'en_US:en', 11:11:49 INFO - 'LOGNAME': 'cltbld', 11:11:49 INFO - 'MAIL': '/var/mail/cltbld', 11:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:49 INFO - 'MOZ_NO_REMOTE': '1', 11:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:49 INFO - 'NO_EM_RESTART': '1', 11:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:49 INFO - 'PWD': '/builds/slave/test', 11:11:49 INFO - 'SHELL': '/bin/bash', 11:11:49 INFO - 'SHLVL': '1', 11:11:49 INFO - 'TERM': 'linux', 11:11:49 INFO - 'TMOUT': '86400', 11:11:49 INFO - 'USER': 'cltbld', 11:11:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:49 INFO - '_': '/tools/buildbot/bin/python'} 11:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:49 INFO - Downloading/unpacking psutil>=3.1.1 11:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:11:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:11:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:11:51 INFO - Installing collected packages: psutil 11:11:51 INFO - Running setup.py install for psutil 11:11:51 INFO - building 'psutil._psutil_linux' extension 11:11:51 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 11:11:51 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 11:11:51 INFO - building 'psutil._psutil_posix' extension 11:11:51 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 11:11:51 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 11:11:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:11:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:11:52 INFO - Successfully installed psutil 11:11:52 INFO - Cleaning up... 11:11:52 INFO - Return code: 0 11:11:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:11:52 INFO - Using env: {'DISPLAY': ':0', 11:11:52 INFO - 'HOME': '/home/cltbld', 11:11:52 INFO - 'LANG': 'en_US.UTF-8', 11:11:52 INFO - 'LANGUAGE': 'en_US:en', 11:11:52 INFO - 'LOGNAME': 'cltbld', 11:11:52 INFO - 'MAIL': '/var/mail/cltbld', 11:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:52 INFO - 'MOZ_NO_REMOTE': '1', 11:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:52 INFO - 'NO_EM_RESTART': '1', 11:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:52 INFO - 'PWD': '/builds/slave/test', 11:11:52 INFO - 'SHELL': '/bin/bash', 11:11:52 INFO - 'SHLVL': '1', 11:11:52 INFO - 'TERM': 'linux', 11:11:52 INFO - 'TMOUT': '86400', 11:11:52 INFO - 'USER': 'cltbld', 11:11:52 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:52 INFO - '_': '/tools/buildbot/bin/python'} 11:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:11:54 INFO - Installing collected packages: mozsystemmonitor 11:11:54 INFO - Running setup.py install for mozsystemmonitor 11:11:54 INFO - Successfully installed mozsystemmonitor 11:11:54 INFO - Cleaning up... 11:11:54 INFO - Return code: 0 11:11:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:11:54 INFO - Using env: {'DISPLAY': ':0', 11:11:54 INFO - 'HOME': '/home/cltbld', 11:11:54 INFO - 'LANG': 'en_US.UTF-8', 11:11:54 INFO - 'LANGUAGE': 'en_US:en', 11:11:54 INFO - 'LOGNAME': 'cltbld', 11:11:54 INFO - 'MAIL': '/var/mail/cltbld', 11:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:54 INFO - 'MOZ_NO_REMOTE': '1', 11:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:54 INFO - 'NO_EM_RESTART': '1', 11:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:54 INFO - 'PWD': '/builds/slave/test', 11:11:54 INFO - 'SHELL': '/bin/bash', 11:11:54 INFO - 'SHLVL': '1', 11:11:54 INFO - 'TERM': 'linux', 11:11:54 INFO - 'TMOUT': '86400', 11:11:54 INFO - 'USER': 'cltbld', 11:11:54 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:54 INFO - '_': '/tools/buildbot/bin/python'} 11:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:54 INFO - Downloading/unpacking jsonschema==2.5.1 11:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:11:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:56 INFO - Downloading functools32-3.2.3-2.tar.gz 11:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:11:56 INFO - warning: no files found matching '*.txt' 11:11:56 INFO - no previously-included directories found matching 'build' 11:11:56 INFO - no previously-included directories found matching 'dist' 11:11:56 INFO - no previously-included directories found matching '.git*' 11:11:56 INFO - Installing collected packages: jsonschema, functools32 11:11:56 INFO - Running setup.py install for functools32 11:11:56 INFO - warning: no files found matching '*.txt' 11:11:56 INFO - no previously-included directories found matching 'build' 11:11:56 INFO - no previously-included directories found matching 'dist' 11:11:56 INFO - no previously-included directories found matching '.git*' 11:11:56 INFO - Successfully installed jsonschema functools32 11:11:56 INFO - Cleaning up... 11:11:57 INFO - Return code: 0 11:11:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:11: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')]} 11:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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')]} 11:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:11:57 INFO - Using env: {'DISPLAY': ':0', 11:11:57 INFO - 'HOME': '/home/cltbld', 11:11:57 INFO - 'LANG': 'en_US.UTF-8', 11:11:57 INFO - 'LANGUAGE': 'en_US:en', 11:11:57 INFO - 'LOGNAME': 'cltbld', 11:11:57 INFO - 'MAIL': '/var/mail/cltbld', 11:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:57 INFO - 'MOZ_NO_REMOTE': '1', 11:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:57 INFO - 'NO_EM_RESTART': '1', 11:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:57 INFO - 'PWD': '/builds/slave/test', 11:11:57 INFO - 'SHELL': '/bin/bash', 11:11:57 INFO - 'SHLVL': '1', 11:11:57 INFO - 'TERM': 'linux', 11:11:57 INFO - 'TMOUT': '86400', 11:11:57 INFO - 'USER': 'cltbld', 11:11:57 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:57 INFO - '_': '/tools/buildbot/bin/python'} 11:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:11:57 INFO - Cleaning up... 11:11:57 INFO - Return code: 0 11:11:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:11: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')]} 11:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11: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')]} 11:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:11:57 INFO - Using env: {'DISPLAY': ':0', 11:11:57 INFO - 'HOME': '/home/cltbld', 11:11:57 INFO - 'LANG': 'en_US.UTF-8', 11:11:57 INFO - 'LANGUAGE': 'en_US:en', 11:11:57 INFO - 'LOGNAME': 'cltbld', 11:11:57 INFO - 'MAIL': '/var/mail/cltbld', 11:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:57 INFO - 'MOZ_NO_REMOTE': '1', 11:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:11:57 INFO - 'NO_EM_RESTART': '1', 11:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:57 INFO - 'PWD': '/builds/slave/test', 11:11:57 INFO - 'SHELL': '/bin/bash', 11:11:57 INFO - 'SHLVL': '1', 11:11:57 INFO - 'TERM': 'linux', 11:11:57 INFO - 'TMOUT': '86400', 11:11:57 INFO - 'USER': 'cltbld', 11:11:57 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:57 INFO - '_': '/tools/buildbot/bin/python'} 11:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:11:57 INFO - Downloading/unpacking blobuploader==1.2.4 11:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:59 INFO - Downloading blobuploader-1.2.4.tar.gz 11:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:11:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:11:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:11:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:11:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:00 INFO - Downloading docopt-0.6.1.tar.gz 11:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:12:00 INFO - Installing collected packages: blobuploader, requests, docopt 11:12:00 INFO - Running setup.py install for blobuploader 11:12:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:12:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:12:00 INFO - Running setup.py install for requests 11:12:00 INFO - Running setup.py install for docopt 11:12:00 INFO - Successfully installed blobuploader requests docopt 11:12:00 INFO - Cleaning up... 11:12:00 INFO - Return code: 0 11:12:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:00 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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:12:00 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 11:12:00 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 11:12:00 INFO - Using env: {'DISPLAY': ':0', 11:12:00 INFO - 'HOME': '/home/cltbld', 11:12:00 INFO - 'LANG': 'en_US.UTF-8', 11:12:00 INFO - 'LANGUAGE': 'en_US:en', 11:12:00 INFO - 'LOGNAME': 'cltbld', 11:12:00 INFO - 'MAIL': '/var/mail/cltbld', 11:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:00 INFO - 'MOZ_NO_REMOTE': '1', 11:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:00 INFO - 'NO_EM_RESTART': '1', 11:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:00 INFO - 'PWD': '/builds/slave/test', 11:12:00 INFO - 'SHELL': '/bin/bash', 11:12:00 INFO - 'SHLVL': '1', 11:12:00 INFO - 'TERM': 'linux', 11:12:00 INFO - 'TMOUT': '86400', 11:12:00 INFO - 'USER': 'cltbld', 11:12:00 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:00 INFO - '_': '/tools/buildbot/bin/python'} 11:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:00 INFO - Running setup.py (path:/tmp/pip-TQAuLk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:01 INFO - Running setup.py (path:/tmp/pip-UqrA3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:01 INFO - Running setup.py (path:/tmp/pip-bCvaz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:01 INFO - Running setup.py (path:/tmp/pip-dYQfJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:01 INFO - Running setup.py (path:/tmp/pip-JZIWIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:01 INFO - Running setup.py (path:/tmp/pip-iw8wbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:01 INFO - Running setup.py (path:/tmp/pip-SgmsYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:01 INFO - Running setup.py (path:/tmp/pip-wQAMFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:01 INFO - Running setup.py (path:/tmp/pip-H0ZPWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:01 INFO - Running setup.py (path:/tmp/pip-fXpJgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:01 INFO - Running setup.py (path:/tmp/pip-GSAbvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:01 INFO - Running setup.py (path:/tmp/pip-vZXjB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:01 INFO - Running setup.py (path:/tmp/pip-swNYpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:01 INFO - Running setup.py (path:/tmp/pip-FVM8cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:01 INFO - Running setup.py (path:/tmp/pip-AIdYdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:02 INFO - Running setup.py (path:/tmp/pip-A2HSqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:02 INFO - Running setup.py (path:/tmp/pip-sjGl__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:12:02 INFO - Running setup.py install for manifestparser 11:12:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Running setup.py install for mozcrash 11:12:02 INFO - Running setup.py install for mozdebug 11:12:02 INFO - Running setup.py install for mozdevice 11:12:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Running setup.py install for mozfile 11:12:02 INFO - Running setup.py install for mozhttpd 11:12:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Running setup.py install for mozinfo 11:12:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Running setup.py install for mozInstall 11:12:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:12:02 INFO - Running setup.py install for mozleak 11:12:03 INFO - Running setup.py install for mozlog 11:12:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Running setup.py install for moznetwork 11:12:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Running setup.py install for mozprocess 11:12:03 INFO - Running setup.py install for mozprofile 11:12:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Running setup.py install for mozrunner 11:12:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Running setup.py install for mozscreenshot 11:12:03 INFO - Running setup.py install for moztest 11:12:03 INFO - Running setup.py install for mozversion 11:12:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:12:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:12:03 INFO - Cleaning up... 11:12:03 INFO - Return code: 0 11:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:03 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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:12:03 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 11:12:03 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 11:12:03 INFO - Using env: {'DISPLAY': ':0', 11:12:03 INFO - 'HOME': '/home/cltbld', 11:12:03 INFO - 'LANG': 'en_US.UTF-8', 11:12:03 INFO - 'LANGUAGE': 'en_US:en', 11:12:03 INFO - 'LOGNAME': 'cltbld', 11:12:03 INFO - 'MAIL': '/var/mail/cltbld', 11:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:03 INFO - 'MOZ_NO_REMOTE': '1', 11:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:03 INFO - 'NO_EM_RESTART': '1', 11:12:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:03 INFO - 'PWD': '/builds/slave/test', 11:12:03 INFO - 'SHELL': '/bin/bash', 11:12:03 INFO - 'SHLVL': '1', 11:12:03 INFO - 'TERM': 'linux', 11:12:03 INFO - 'TMOUT': '86400', 11:12:03 INFO - 'USER': 'cltbld', 11:12:03 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:03 INFO - '_': '/tools/buildbot/bin/python'} 11:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:04 INFO - Running setup.py (path:/tmp/pip-pCOxLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:04 INFO - Running setup.py (path:/tmp/pip-UVdMZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:04 INFO - Running setup.py (path:/tmp/pip-zQLO9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:04 INFO - Running setup.py (path:/tmp/pip-P7TrJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:04 INFO - Running setup.py (path:/tmp/pip-vNgKER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:04 INFO - Running setup.py (path:/tmp/pip-80Wwzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:04 INFO - Running setup.py (path:/tmp/pip-mYaA6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:04 INFO - Running setup.py (path:/tmp/pip-sazxpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:04 INFO - Running setup.py (path:/tmp/pip-BQ_RFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:04 INFO - Running setup.py (path:/tmp/pip-4lOvYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:04 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)) 11:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:04 INFO - Running setup.py (path:/tmp/pip-Wcidaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:05 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)) 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:05 INFO - Running setup.py (path:/tmp/pip-SkAS5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:05 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)) 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:05 INFO - Running setup.py (path:/tmp/pip-Kyanb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:05 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)) 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:05 INFO - Running setup.py (path:/tmp/pip-7DQcKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:05 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)) 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:05 INFO - Running setup.py (path:/tmp/pip-2Nf1uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:05 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)) 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:05 INFO - Running setup.py (path:/tmp/pip-Eb1RV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:05 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)) 11:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:05 INFO - Running setup.py (path:/tmp/pip-6zDIz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:05 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)) 11:12:05 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)) 11:12:05 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)) 11:12:05 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)) 11:12:05 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)) 11:12:05 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)) 11:12:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:05 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 11:12:05 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 11:12:05 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 11:12:05 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 11:12:05 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 11:12:05 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 11:12:07 INFO - Downloading blessings-1.6.tar.gz 11:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:12:07 INFO - Installing collected packages: blessings 11:12:07 INFO - Running setup.py install for blessings 11:12:07 INFO - Successfully installed blessings 11:12:07 INFO - Cleaning up... 11:12:07 INFO - Return code: 0 11:12:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:07 INFO - Reading from file tmpfile_stdout 11:12:07 INFO - Current package versions: 11:12:07 INFO - argparse == 1.2.1 11:12:07 INFO - blessings == 1.6 11:12:07 INFO - blobuploader == 1.2.4 11:12:07 INFO - docopt == 0.6.1 11:12:07 INFO - functools32 == 3.2.3-2 11:12:07 INFO - jsonschema == 2.5.1 11:12:07 INFO - manifestparser == 1.1 11:12:07 INFO - mozInstall == 1.12 11:12:07 INFO - mozcrash == 0.17 11:12:07 INFO - mozdebug == 0.1 11:12:07 INFO - mozdevice == 0.48 11:12:07 INFO - mozfile == 1.2 11:12:07 INFO - mozhttpd == 0.7 11:12:07 INFO - mozinfo == 0.9 11:12:07 INFO - mozleak == 0.1 11:12:07 INFO - mozlog == 3.3 11:12:07 INFO - moznetwork == 0.27 11:12:07 INFO - mozprocess == 0.23 11:12:07 INFO - mozprofile == 0.28 11:12:07 INFO - mozrunner == 6.12 11:12:07 INFO - mozscreenshot == 0.1 11:12:07 INFO - mozsystemmonitor == 0.3 11:12:07 INFO - moztest == 0.7 11:12:07 INFO - mozversion == 1.4 11:12:07 INFO - psutil == 3.1.1 11:12:07 INFO - requests == 1.2.3 11:12:07 INFO - wsgiref == 0.1.2 11:12:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:07 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')]} 11:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:07 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')]} 11:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:07 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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:12:07 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 11:12:07 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 11:12:07 INFO - Using env: {'DISPLAY': ':0', 11:12:07 INFO - 'HOME': '/home/cltbld', 11:12:07 INFO - 'LANG': 'en_US.UTF-8', 11:12:07 INFO - 'LANGUAGE': 'en_US:en', 11:12:07 INFO - 'LOGNAME': 'cltbld', 11:12:07 INFO - 'MAIL': '/var/mail/cltbld', 11:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:07 INFO - 'MOZ_NO_REMOTE': '1', 11:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:07 INFO - 'NO_EM_RESTART': '1', 11:12:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:07 INFO - 'PWD': '/builds/slave/test', 11:12:07 INFO - 'SHELL': '/bin/bash', 11:12:07 INFO - 'SHLVL': '1', 11:12:07 INFO - 'TERM': 'linux', 11:12:07 INFO - 'TMOUT': '86400', 11:12:07 INFO - 'USER': 'cltbld', 11:12:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:07 INFO - '_': '/tools/buildbot/bin/python'} 11:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 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)) 11:12:08 INFO - Cleaning up... 11:12:08 INFO - Return code: 0 11:12:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:12:08 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 0x273a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x289d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29fb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', '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 11:12:08 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 11:12:08 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 11:12:08 INFO - Using env: {'DISPLAY': ':0', 11:12:08 INFO - 'HOME': '/home/cltbld', 11:12:08 INFO - 'LANG': 'en_US.UTF-8', 11:12:08 INFO - 'LANGUAGE': 'en_US:en', 11:12:08 INFO - 'LOGNAME': 'cltbld', 11:12:08 INFO - 'MAIL': '/var/mail/cltbld', 11:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:08 INFO - 'MOZ_NO_REMOTE': '1', 11:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:08 INFO - 'NO_EM_RESTART': '1', 11:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:08 INFO - 'PWD': '/builds/slave/test', 11:12:08 INFO - 'SHELL': '/bin/bash', 11:12:08 INFO - 'SHLVL': '1', 11:12:08 INFO - 'TERM': 'linux', 11:12:08 INFO - 'TMOUT': '86400', 11:12:08 INFO - 'USER': 'cltbld', 11:12:08 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:08 INFO - '_': '/tools/buildbot/bin/python'} 11:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:12:08 INFO - Cleaning up... 11:12:08 INFO - Return code: 0 11:12:08 INFO - Running post-action listener: _resource_record_post_action 11:12:08 INFO - Running post-action listener: _start_resource_monitoring 11:12:08 INFO - Starting resource monitoring. 11:12:08 INFO - [mozharness: 2016-10-05 18:12:08.282249Z] Finished create-virtualenv step (success) 11:12:08 INFO - [mozharness: 2016-10-05 18:12:08.282683Z] Running install step. 11:12:08 INFO - Running pre-action listener: _resource_record_pre_action 11:12:08 INFO - Running main action method: install 11:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:08 INFO - Reading from file tmpfile_stdout 11:12:08 INFO - Detecting whether we're running mozinstall >=1.0... 11:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:12:08 INFO - Reading from file tmpfile_stdout 11:12:08 INFO - Output received: 11:12:08 INFO - Usage: mozinstall [options] installer 11:12:08 INFO - Options: 11:12:08 INFO - -h, --help show this help message and exit 11:12:08 INFO - -d DEST, --destination=DEST 11:12:08 INFO - Directory to install application into. [default: 11:12:08 INFO - "/builds/slave/test"] 11:12:08 INFO - --app=APP Application being installed. [default: firefox] 11:12:08 INFO - mkdir: /builds/slave/test/build/application 11:12:08 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'] 11:12:08 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 11:12:22 INFO - Reading from file tmpfile_stdout 11:12:22 INFO - Output received: 11:12:22 INFO - /builds/slave/test/build/application/firefox/firefox 11:12:22 INFO - Running post-action listener: _resource_record_post_action 11:12:22 INFO - [mozharness: 2016-10-05 18:12:22.091493Z] Finished install step (success) 11:12:22 INFO - [mozharness: 2016-10-05 18:12:22.091768Z] Running run-tests step. 11:12:22 INFO - Running pre-action listener: _resource_record_pre_action 11:12:22 INFO - Running main action method: run_tests 11:12:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:12:22 INFO - Python 2.7.3 11:12:22 INFO - Return code: 0 11:12:22 INFO - grabbing minidump binary from tooltool 11:12:22 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')]} 11:12:22 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 0x29f93b0>, '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 0x29fa2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29fa740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:12:22 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 11:12:22 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 11:12:22 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 11:12:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:12:22 INFO - Return code: 0 11:12:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:12:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:12:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:12:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:12:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:12:22 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/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 11:12:22 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/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 11:12:22 INFO - Using env: {'DISPLAY': ':0', 11:12:22 INFO - 'HOME': '/home/cltbld', 11:12:22 INFO - 'LANG': 'en_US.UTF-8', 11:12:22 INFO - 'LANGUAGE': 'en_US:en', 11:12:22 INFO - 'LOGNAME': 'cltbld', 11:12:22 INFO - 'MAIL': '/var/mail/cltbld', 11:12:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:12:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:22 INFO - 'MOZ_NO_REMOTE': '1', 11:12:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:12:22 INFO - 'NO_EM_RESTART': '1', 11:12:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:22 INFO - 'PWD': '/builds/slave/test', 11:12:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:12:22 INFO - 'SHELL': '/bin/bash', 11:12:22 INFO - 'SHLVL': '1', 11:12:22 INFO - 'TERM': 'linux', 11:12:22 INFO - 'TMOUT': '86400', 11:12:22 INFO - 'USER': 'cltbld', 11:12:22 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191', 11:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:22 INFO - '_': '/tools/buildbot/bin/python'} 11:12:22 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/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 11:12:22 INFO - mozversion application_buildid: 20161005080800 11:12:22 INFO - mozversion application_changeset: dacddb224d32cd5f979fa4b53e702c7dc69be46c 11:12:22 INFO - mozversion application_display_name: Firefox 11:12:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:12:22 INFO - mozversion application_name: Firefox 11:12:22 INFO - mozversion application_remotingname: firefox 11:12:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:12:22 INFO - mozversion application_vendor: Mozilla 11:12:22 INFO - mozversion application_version: 50.0 11:12:22 INFO - mozversion platform_buildid: 20161005080800 11:12:22 INFO - mozversion platform_changeset: dacddb224d32cd5f979fa4b53e702c7dc69be46c 11:12:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:12:22 INFO - mozversion platform_version: 50.0 11:12:22 INFO - using testdate: 1475691142 11:12:22 INFO - actual date: 1475691142 11:12:22 INFO - starting webserver on 'localhost:60058' 11:12:22 INFO - SUITE-START | Running 2 tests 11:12:22 INFO - TEST-START | dromaeo_css 11:12:22 INFO - operating with platform_type : linux_ 11:12:22 INFO - Initialising browser for dromaeo_css test... 11:12:22 INFO - TEST-INFO | started process 3199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0M6Sn4/profile http://localhost:60058/getInfo.html) 11:12:26 INFO - PROCESS | 3199 | __metrics Screen width/height:1600/1200 11:12:26 INFO - PROCESS | 3199 | colorDepth:24 11:12:26 INFO - PROCESS | 3199 | Browser inner width/height: 1024/697 11:12:26 INFO - PROCESS | 3199 | __metrics 11:12:26 INFO - PROCESS | 3199 | 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" 11:12:26 INFO - PROCESS | 3199 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:12:27 INFO - PROCESS | 3199 | console.error: PushService: 11:12:27 INFO - PROCESS | 3199 | stateChangeProcessEnqueue: Error transitioning state 11:12:27 INFO - PROCESS | 3199 | UnknownError 11:12:27 INFO - TEST-INFO | 3199: exit 0 11:12:27 INFO - Browser initialized. 11:12:27 INFO - Running cycle 1/1 for dromaeo_css test... 11:12:27 INFO - TEST-INFO | started process 3284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0M6Sn4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:39 INFO - PROCESS | 3284 | RSS: Main: 143679488 11:12:39 INFO - PROCESS | 3284 | 11:13:31 INFO - PROCESS | 3284 | 1475691211327 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] 11:15:38 INFO - PROCESS | 3284 | Cycle 1(1): loaded http://localhost:60058/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60058/tests/dromaeo/cssquery-ext.html) 11:15:40 INFO - PROCESS | 3284 | RSS: Main: 268218368 11:15:40 INFO - PROCESS | 3284 | 11:18:42 INFO - PROCESS | 3284 | Cycle 1(1): loaded http://localhost:60058/tests/dromaeo/cssquery-ext.html (next: http://localhost:60058/tests/dromaeo/cssquery-jquery.html) 11:18:43 INFO - PROCESS | 3284 | RSS: Main: 229163008 11:18:43 INFO - PROCESS | 3284 | 11:21:44 INFO - PROCESS | 3284 | Cycle 1(1): loaded http://localhost:60058/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60058/tests/dromaeo/cssquery-mootools.html) 11:21:45 INFO - PROCESS | 3284 | RSS: Main: 174895104 11:21:45 INFO - PROCESS | 3284 | 11:24:47 INFO - PROCESS | 3284 | Cycle 1(1): loaded http://localhost:60058/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60058/tests/dromaeo/cssquery-prototype.html) 11:24:48 INFO - PROCESS | 3284 | RSS: Main: 175435776 11:24:48 INFO - PROCESS | 3284 | 11:27:50 INFO - PROCESS | 3284 | Cycle 1(1): loaded http://localhost:60058/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60058/tests/dromaeo/cssquery-yui.html) 11:27:51 INFO - PROCESS | 3284 | RSS: Main: 173174784 11:27:51 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | Cycle 1(1): loaded http://localhost:60058/tests/dromaeo/cssquery-yui.html (next: http://localhost:60058/tests/dromaeo/cssquery-dojo.html) 11:30:53 INFO - PROCESS | 3284 | RSS: Main: 264126464 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | __start_tp_report 11:30:53 INFO - PROCESS | 3284 | _x_x_mozilla_page_load 11:30:53 INFO - PROCESS | 3284 | _x_x_mozilla_page_load_details 11:30:53 INFO - PROCESS | 3284 | |i|pagename|runs| 11:30:53 INFO - PROCESS | 3284 | |0;dojo.html;3240.7245384142348;3267.215868191633;3280.294736631624;3308.6360500344945;3331.850066746996;5617.971910140449;5662.263905692254;5689.914651280229;5703.600747947635;5707.286589176353;5499.670019798815;5531.917021244684;5564.582656300783;5570.359408668004;5592.3009623797025;4989.077020751172;5069.721165335905;5097.464766199551;5114;5142.3829140503;5055.9241611375855;5117.1556693145585;5138.9743051284695;5143.485651434845;5153.407358153804;5033.446320904697;5047.369078865142;5056.696598204101;5124.743762811863;5129.2562578426105;5143.8456846294675;5177.068127737006;5178.14560597501;5224.63427560071;5237.6071794615555;5064.594832413435;5139.817841896384;5199.792008319662;5214.478552144793;5239.607029472805;5411.972940135313;5511.807086751945;5516.14499752539;5544.390117087117;5584.106542953108;5884.323302820145;5907.822765317047;6007.279126504847;6032.245969253843;6032.909506357408;5862.560307976919;6007.909881351783;6021;6027.638341699469;6028.517718582503;5607.439256074401;5634.3520495143175;5638.210650508891;5665;5729.312482502127;6213.65824879627;6215.06773983897;6215.316315205414;6216.160818289563;6219.564630475823;5802.21670074543;5928.288605367384;5973.432523910222;6003.339632640406;6003.8198854034445;6033.909491357633;6099;6101.079068467482;6106.107606160077;6112.1995365628145;6081.331053584102;6089.5737298388685;6089.634621922699;6101.450869421773;6110.480609148272;5802.390748971297;5911.172435858984;6040.124181993587;6089.725962331706;6092.7562897484495;4586.289125185602;4590.770461476913;4601.769911504478;4614.238650622677;4615.699979501322;4077.408775727503;4109.630133288018;4119.402686610485;4124.896877578025;4145.005198752338;5549.972250138804;5761.049426844607;5778.422157784188;5779.739911703983;5788.507976822018;6076.483498902643;6127.769884593388;6148.508119350441;6151.661658608819;6159.23009623797;5847.707614619337;5851.765929362863;5855.677937713466;5855.912161317584;5881.411858814085;5088.236764485357;5166.5866730661455;5270.077736396115;5272.367315922113;5274.156135018456;6081.695915204311;6178.7219575119225;6205.875882482415;6208.1308616793685;6210.099535567408;6024.457798798129;6028.668423236764;6032;6038.094285857156;6077.696115194134;6027.758889644365;6117.143599895929;6125.418593525838;6180.938190618036;6182.783602573888;5880.088586269135;5963.970180149072;5965.28416590012;5967.582269241111;5991.700414979321;6079.756809727562;6081.878362432815;6084.939150608438;6085.786997455068;6094.75620975156;5711;5777.3356064051495;5940.613860099253;5940.990031694536;5941;5789.189513468202;5860.531157507304;5860.970695146497;5891.734871930859;5893.469587737124;5774.047282198559;5777.133429985536;5779.768809247751;5782.768689252551;5817.6509409433875;5750.683712395691;5804.796832110856;5813.3895940925595;5834.358220595646;5887.116932460165;5683.374828768915;5686.2607860977805;5687.77248910039;5718.370979192368;5719.771209151588 11:30:53 INFO - PROCESS | 3284 | |1;ext.html;579.904315787895;618.7370367593771;643.1478291264076;643.4787821864289;657.3360905485458;37518;46977.76511117444;48111.31610393628;48125.27812082813;50982.74508627452;49322.260166097534;49724.751376243155;49790.253146202835;49855.750721246346;49858.252126218045;8534.914650853505;9185.265178785714;9352.81294374112;9358.532073396336;11057.11543076552;10920.18098642601;11374.601888933888;11380.203385763;11386.60146894859;11388.088952883749;11373.545058197704;11436.599719009837;11437.542498300058;11446;11451.25566838153;11336.036436902874;11372.976432121108;11377.431128443544;11379.544818207261;11387.430628468626;9195.08049195082;9477.099675530813;9483.099105584959;9766.462844543546;9782.070703283176;45366.31950520745;45848.3122753159;45969.54030459686;45995.54004459946;45997.310040349425;3921.431392448079;4087.9591204087874;4264.23243816113;4273.423087883127;4619.122366750306;34279;34286.48570271448;34361.82819085888;34664.30671386584;35269.82365088184;4566.520515345907;4690.273007683814;4705.458872229703;4727.858164255078;4789.042191561702;244595;248866;252559;252675.73662131754;253276;63393;63742;63803.36196637974;63808.68095659492;63819;40257.396139057935;40828;41104.38343424851;43303.56696433058;43321.783391083474;19868.60262794736;20029.09869055896;20054.699179512318;20058.59882802365;20068.197272109242;12229.143959922898;15163.317650705523;15370.846291537166;15457.768133478006;15461.613459663373;20783.272585459436;20868.165273389197;20950.89524552398;20957.89521052385;20998.790012099988;7472.887088567229;7582.54504729718;7696.884546731803;7702.191269916578;7725.768226953201;999.3054826895238;1071.2501249125644;1084.8426978088132;1098.4452851310198;1179.728662407651;35454.8227258862;35554;35579;35736.64263357385;35948.281034379164;1129.7232178116249;1187.1927089578996;1196.407778149804;1205.9035277177804;1233.216907263876;18383.72424413635;18665.720014199796;18689.81310186908;18869.339573114874;18949;476864;654573;660938;662616;667551;64628;65132;65158.34841651618;65199.674001630636;65278;1316.6839958410105;1456.6795305032877;1475.6679747056826;1485.762278035509;1522.824875139351;34375.48436773501;35615.46576801298;36205.45691814572;36393.514027203324;37049.814750926074;54906;55712.72143639418;55724.72137639286;55728.44271557395;55804.720976396486;481078;486494;486551;486613;486806;29202.56196157017;30274;30497.54253686241;31800.522992154674;32392.514112288805;51832;52142;52194.739026304625;52439;52649;42937;43425.3486197701;43846.561534385524;45532;45755;43184;43727.56272437362;45157.96789144562;45337.77331113455;47622;46432.7678361606;47658.523414766794;47719;47836.28245576249;47857.282140767216;38610.80694596509;41114.17771644491;41147;41695.7915210422;41802;30891.69108308888;30941.69058309478;31180.688193118687;31189.220269492536;31379.686203137677 11:30:53 INFO - PROCESS | 3284 | |2;jquery.html;430.39099673961346;431.1351072125723;438.6381235480728;442.230518897119;449.3349842233494;19041;20610.89694551527;21010;21097.47256318593;21456.249031283907;20367.490812729688;21071.683924741104;21186;21309.57380852382;21327.893360533177;5763.452472015152;5993.850153746148;6006.189164462785;6007.8197654070445;6008.24896887889;5607.943920560803;5864.618799778001;5891.7054147292465;5895.292564892219;5896.204012458327;5797.65214087154;5852.414758524156;5868.9413105868825;5869.677167755772;5869.9706501467435;5769.913451298234;5812.796552120677;5813.6802475863815;5813.79651712188;5814.360420353758;5191.299174611417;5270.92093618596;5273.1299335609965;5294.100002999499;5325.254464374991;24499.755002449925;24677;24687.753122468905;25099.62350564743;25212;3176.5870436843293;3246.3507298540385;3322.2026713588566;3403.268297316064;3417.3165366926714;14841.406343746237;15515.379384824593;15760.842391576052;16008.119553424556;16378.754318685229;1982.3755517012096;2025.5341271507489;2076.096897849425;2091.5084955035554;2102.9158833646634;57874;74754.25245747528;74893.25106748917;80976;82541;11042.613508527164;11214.607488737855;11306.095512358992;11528.481218345194;11575.305481671126;15726.9213653931;15736.763948540782;15745.370185192693;15768.132752698712;15802.920985395229;8603.913960860436;8635.956820215859;8774.298056155492;8899.599518021705;8905.643774249087;8428.74713758576;8517.701880434155;8553.615087321086;8612.741617751477;8683.826323473495;6839.623820689811;6942.7222911084;6977.6511174441075;7020.684069216897;7035.753748618774;1816.747213729175;1918.788933217345;1935.4097000414727;2017.7982201779705;2049.282751037125;473.1884817537939;1033.2618560813753;1035.8653375061344;1049.1292227451197;1065.4619417194267;12293.56972505958;13210.405531751094;13231.867681323063;13242.470301187845;13296.933515332556;476.6234674607091;477.1054273237679;487.8560824556693;494.83175720254565;503.07434320849814;7067.858642827115;7090.610016449042;7202.855942881218;7221.53060051095;7249.311315425026;388922;394373;397493;538128;563370;102209.48895255476;105886;107227.46386268019;108376;110235.44882275537;1028.3264461777549;1049.0296475759892;1049.7008352619466;1062.4740753327153;1071.0681706915034;18296.176672050056;18758;18812.623747525246;18841.71737423967;18991.24035038583;43353.783231083646;43691.78154109209;44076.55923440724;44760.328595071755;44762.55237447584;61942.45143871259;62184;62675.05987410284;63313.36686633075;64770.35229647832;8295.585220738818;8846.646134154562;8931.330150238628;8991.640334386551;9183.816323673624;12360.938195308965;13527.120737152054;13564.525241616497;13846.65383365424;13877.444902203799;11507.769844603228;12082.637520874388;12113.333766642567;12153.210041347284;12207.45066471993;11221.270617409842;11400.486978086172;11686.766264674488;11694.766104678029;12064.095192860395;11954.222975506564;11964.521419143484;11997.82003269969;12059.21615095016;12248.693782655504;7996.56018918965;8507.617157227816;8519.361047921308;8605.698800541952;8653.091425400495;11944.343061131722;12003.339816310187;12004.2197257178;12269.079819013432;12430.81353779676 11:30:53 INFO - PROCESS | 3284 | |3;mootools.html;1536.1474381718147;1552.728272552303;1708.8034875989265;1776.9289228430864;1832.7525784019163;9193.494357810327;9878.851817222738;9935.751606209831;9943.801123977517;9944.154746846527;8451.830963380744;9176.265898728103;9329.580168892398;9381.859272110923;9650.806983860319;2435.719892212396;2476.418041760185;2478.1821998740375;2486.3038349262238;2486.8383555068863;2359.6460530920326;2439.134107391877;2455.3247856839334;2455.9754402456015;2458.3485376375274;2420.213688126289;2429.842060266086;2479.838810477313;2481.0323973650293;2484.4037431016604;2340.0756701103055;2347.389678683546;2347.788699017088;2363.196513185508;2399.1123284384716;2381.4641705616273;2385.1294277588727;2387.8089752819733;2390.6294524348755;2402.423418379594;9377.1091746284;9814.46020468874;9857.852132218022;9888.456134912576;9905.653302134391;5402.37872644647;5510.779568817243;5527.419620939824;5528.695921724344;5532.114861622162;3323.88366407177;3397.167693914982;3435.3644575753315;3475.044362800225;3507.2108775525303;1234.2717796500108;1252.9436175372039;1268.5052829396543;1288.2978776566792;1299.1815156451376;13199.86800131996;13265.933670331586;13287.269200193889;13335.533091359943;13358.732825343439;4747.620190384761;4762.547557982056;4815.3499277597775;4852.951470485321;4959.801607935642;11270.605528806616;11297.943510282561;11310.377929213808;11313.604023859125;11320.71698207535;5537.916931246034;5576.804811831566;5583.022970980062;5584.63699859516;5591.580631452657;4643.955110100201;5001.824936127218;5061.620378471629;5110.744462776846;5122.282880396748;4180.895477613084;4274.829006839754;4425.690201685916;4471.128130014681;4478.440194975628;1386.202933313349;1417.7660685986696;1418.156197062746;1426.3652674559928;1443.8483959184343;1071.978560428787;1074.6722249713912;1082.86464191976;1091.6561283195767;1094.8905109488987;6452.4515416189215;6626;6656.301088385747;6671;6823.078884350648;475.28944228378793;475.59099174709723;478.8204423341247;486.68365562384025;496.3150851824531;4429.734215947054;4462.888427789331;4519.435070616173;4556;4615.215413379734;11646.88353116458;11922.225055371373;12009.459574319177;12036.759264814655;12039.578614748441;11401.486933087865;11966.58116965902;11970.940145299392;11974.221675591232;11981.760364792655;8743.300535957109;9168.724938251864;9171.58727857248;9181.632734690538;9206.493642849708;6616.66916654175;6691;6700.2964688708935;6818.454523638196;6892.138482689664;10635.415052172204;11153.051990580892;11173.944130279297;11335.659930202108;11441.256318339281;11428.428578570873;11450.885491145315;11453.427328633701;11568.305901645928;11586.42067896585;7552.9244707552225;7847.84304313922;7854.057513098464;8264.793380165544;8520.573971301534;9246.398984066014;9545.809083818423;9557.52212389364;9800.215982721222;9813.26400519978;8419.747407577783;8598.398112132341;8647.697330593399;8679.956600216958;8758.649654013769;8216.137305582828;8229.876551851607;8422.157784221528;8505.872411913702;8738.606762695821;7252.927470725225;7412;7469.178390377163;7633.389328853567;7638.732644357421;5655.632383895034;5788.27646544182;5873.295204575478;5893.823185304448;5912.733926973383;5752.28096487939;5777.24895763548;5782.045962416154;5837.445442682938;5845.26991980199 11:30:53 INFO - PROCESS | 3284 | |4;prototype.html;353.87791212031846;355.57508777011475;358.88515674984;359.28860855506116;374.42712649646035;5554.194641776945;5562.276904002484;5587.960394456025;5588.888222235553;5591.580631452642;5457.727113644321;5465.9180112298345;5508.614396992202;5517.2551705519745;5549.472800083986;4199.887029937069;4287.1425714857;4343.174796788616;4347.543507931669;4367.3448982652535;4308.504521979981;4326.178026175033;4327.480702315726;4339.500957389893;4384.583464570861;4144.171165766858;4325.891852703676;4341.978290108561;4344.804483798221;4345.3047512398025;4141.585841415849;4282.9999999999845;4290.69965102443;4314.309710446313;4322.286822674255;3820.789856557888;3828.1769419574925;3836.3094642964256;3842.1163132479405;3844.692424606025;5186.5850731941355;5418.3450109446485;5445.945540544583;5453.509184173404;5475.370332411784;4944.258361245806;4944.456109827916;4945.505449455061;4964.528369804862;4990.228468893563;5721.482547679049;5729.195303479432;5732.742026608813;5770.884582308372;5838.766449342022;4046.2514434829354;4216.51510076342;4218.261804184255;4245.299525578276;4249;5996.19051428056;6001.369856165084;6010.338862725097;6015.729292181863;6042.45917291088;4280.5505421930875;4297.82808687649;4301;4310.353446982977;4344.587264209895;4149.543550209474;4394.868153955386;4397.824087036547;4400.911981760347;4410.514843367227;4901.828436004723;4910.533499317559;4919.631027672939;4921.434035085939;4951.10880041596;5788;5796.739146738407;5847.88890110886;5870.178175055496;5877.764889404461;1446.5443385333585;1490.6646004648867;1495.8878084143732;1520.065159926637;1523.405871710034;945.5225111318878;962.6582563189967;975.8095123948771;976.7497603068084;991.3457118302031;4960.429550601655;4961.90076198474;4977.552020318117;4977.676451030671;5006.69959802413;5188.81839135636;5190.8961820764125;5211.895762084737;5226.2230841514065;5242.895142097137;4993.251012348177;5438.265834112344;5459.754311056012;5534.584906132056;5608.355039170548;3125.406172827201;3157.3369592385857;3183.7293830024246;3207.8716851326153;3213.4055199787645;5908.734106965197;6142.692865356804;6146.846328841725;6163.3836616338;6173.629582225081;6042.154098426224;6043.637381757108;6116.204893363834;6119.724612392453;6123.6019658722935;6139.263288405331;6144.17053697763;6149.2313460817395;6164.291106522717;6175.382461753788;5736.340320862987;5920.763169473173;5992.161097446274;6000.819975400745;6016.2479690038745;5936.5844390892225;5941.287045554561;5952.5237980960555;5969.343372228965;5999.580029398075;5841.649501029951;6100.206973093469;6115.327313995546;6187.257529096537;6217.440430361108;4226.239276930182;4258.425112609758;4263.3818096375235;4264.722793018444;4271.700980931305;5983.192269043798;6049.30433000193;6065.695875386813;6081.300650425258;6091.451769340781;5701.859628074485;5801.680907550125;5814.30228372598;5829.7959571416495;5866.882662346644;5809.070548712186;5810.854728631818;5813.3605303417435;5840.014395105514;5893.469587737124;5545.944540554704;5764.184281951172;5777.45114214149;5781.4507621775865;5840.838889610871;5859.912101318569;5870.26621672291;5880.382559831156;5882.441168089024;5895.7618900031475;5725.51333136688;5764.452377024176;5780.826575202751;5783.508401785895;5784.161296611884 11:30:53 INFO - PROCESS | 3284 | |5;yui.html;818.6725309876052;827.6565225431447;851.5784686580143;875.6759998800444;915.2220612479393;16368.508944731662;16516.66966660666;17316.393926212586;17318.134093295346;17384.13079346034;16545.66908661826;16568.66862662746;16759.83240167598;16768.41310554131;17312.56718582036;737.0455260437719;877.3332267476732;898.5911410308327;900.9864852027225;915.5651065743751;885.663447889801;886.6231851463135;886.9246114080311;889.2485849457198;889.3241136736095;917.2020342302206;923.5613083785215;923.6028507741669;924.7872989212485;927.8422668146432;923.958421871014;924.5793164110381;924.8890133184027;925.2967744514184;926.0878035135386;680.9285025072346;681.6762038031944;681.6898311268411;683.0505597219868;683.7572661705123;16746.916265418597;16756;16862.83137168625;16886.324547018103;16947.237374318185;15572.299246533934;15736.527904162893;15798.763018554731;15948.2823272953;16110;12913.225206487547;13405.329733513285;13555.728885422237;13575.45698172072;13593.116447430886;4010.7794071326066;4088.509378874554;4145.730527515732;4156.8129434175535;4229.407882896397;77504;77819.61090194626;78034.21965780326;79237.60381198114;80044.19955800484;12410.75178496443;12487.875121248853;12492.06309526771;12625.810612840814;12761.93619031899;22057.11771529153;22185;22220.888895555418;22248.77751222467;22303.665445018014;8688;8755.430896991675;8776.473411595325;8779.956100219459;9342.95328523353;5121.513456221653;5174.844754657366;5294.232336311214;5302.045631786315;5321.042212401805;3614.6566076222716;3766.0208345829733;3780.4518344839844;3906.746061505993;4104.384342348611;699.1854489519652;700.0863872646246;703.8873780195145;705.5590256089944;708.4226355520235;703.7466512055656;732.4433430592787;799.3605115907251;812.5571563497908;823.4153750836973;10677.78644427107;11070.225084244026;11175.552977880794;11372.203945723886;11637.941810291064;781.4607920534813;813.9633716482773;814.8777683347427;821.1214001018972;825.1865606800108;5227.372715274191;5399.568034557226;5405.594580406485;5435.130379139318;5483.670979741229;93955.53022234845;122894;129360.35319823341;130059.34970325087;131241.34379328234;111484.4425777866;114992.42503787427;116123;116181.41909290568;116506;61575;97651;100104.4994775036;100475;103140;10134.898651013396;10184.885498463758;10226.846597301195;10338.224633152695;10416.635417760343;46986.530134698216;48042;57949;58847.11328404259;58915;76623;78008;78013.2198678006;78094;79353.20646793458;14630.561083167522;14681.19253441071;14743.557693269218;14770.33533490974;14786.48247311382;16626;16691.08199049064;16769.496915092568;16779.748303775697;16844.915775421043;14443.277836107942;14449.205293708947;14688.339024743698;14697.92651036738;14811.481598144013;15277.805406911191;15367.61580960485;15406.691866162839;15991.200439977722;16097.275622596777;13652.65868353299;14111.364988575333;14552.563423097325;14639.560813175622;15314.081155130727;12219.87780122223;12604.55884044054;12744.745105097662;12849.87150128524;12878.871211288142;11870.465829037887;11902.761944761229;12040.939795300967;12217.877821221675;12330.938345308216 11:30:53 INFO - PROCESS | 3284 | __end_tp_report 11:30:53 INFO - PROCESS | 3284 | __start_cc_report 11:30:53 INFO - PROCESS | 3284 | _x_x_mozilla_cycle_collect,1068 11:30:53 INFO - PROCESS | 3284 | __end_cc_report 11:30:53 INFO - PROCESS | 3284 | __startTimestamp1475692253810__endTimestamp 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | ------- Summary: start ------- 11:30:53 INFO - PROCESS | 3284 | Number of tests: 6 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | [#0] dojo.html Cycles:165 Average:5596.47 Median:5789.19 stddev:632.17 (10.9%) stddev-sans-first:606.50 11:30:53 INFO - PROCESS | 3284 | Values: 3240.7 3267.2 3280.3 3308.6 3331.9 5618.0 5662.3 5689.9 5703.6 5707.3 5499.7 5531.9 5564.6 5570.4 5592.3 4989.1 5069.7 5097.5 5114.0 5142.4 5055.9 5117.2 5139.0 5143.5 5153.4 5033.4 5047.4 5056.7 5124.7 5129.3 5143.8 5177.1 5178.1 5224.6 5237.6 5064.6 5139.8 5199.8 5214.5 5239.6 5412.0 5511.8 5516.1 5544.4 5584.1 5884.3 5907.8 6007.3 6032.2 6032.9 5862.6 6007.9 6021.0 6027.6 6028.5 5607.4 5634.4 5638.2 5665.0 5729.3 6213.7 6215.1 6215.3 6216.2 6219.6 5802.2 5928.3 5973.4 6003.3 6003.8 6033.9 6099.0 6101.1 6106.1 6112.2 6081.3 6089.6 6089.6 6101.5 6110.5 5802.4 5911.2 6040.1 6089.7 6092.8 4586.3 4590.8 4601.8 4614.2 4615.7 4077.4 4109.6 4119.4 4124.9 4145.0 5550.0 5761.0 5778.4 5779.7 5788.5 6076.5 6127.8 6148.5 6151.7 6159.2 5847.7 5851.8 5855.7 5855.9 5881.4 5088.2 5166.6 5270.1 5272.4 5274.2 6081.7 6178.7 6205.9 6208.1 6210.1 6024.5 6028.7 6032.0 6038.1 6077.7 6027.8 6117.1 6125.4 6180.9 6182.8 5880.1 5964.0 5965.3 5967.6 5991.7 6079.8 6081.9 6084.9 6085.8 6094.8 5711.0 5777.3 5940.6 5941.0 5941.0 5789.2 5860.5 5861.0 5891.7 5893.5 5774.0 5777.1 5779.8 5782.8 5817.7 5750.7 5804.8 5813.4 5834.4 5887.1 5683.4 5686.3 5687.8 5718.4 5719.8 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | [#1] ext.html Cycles:180 Average:63191.46 Median:23033.12 stddev:128205.76 (556.6%) stddev-sans-first:128479.23 11:30:53 INFO - PROCESS | 3284 | Values: 579.9 618.7 643.1 643.5 657.3 37518.0 46977.8 48111.3 48125.3 50982.7 49322.3 49724.8 49790.3 49855.8 49858.3 8534.9 9185.3 9352.8 9358.5 11057.1 10920.2 11374.6 11380.2 11386.6 11388.1 11373.5 11436.6 11437.5 11446.0 11451.3 11336.0 11373.0 11377.4 11379.5 11387.4 9195.1 9477.1 9483.1 9766.5 9782.1 45366.3 45848.3 45969.5 45995.5 45997.3 3921.4 4088.0 4264.2 4273.4 4619.1 34279.0 34286.5 34361.8 34664.3 35269.8 4566.5 4690.3 4705.5 4727.9 4789.0 244595.0 248866.0 252559.0 252675.7 253276.0 63393.0 63742.0 63803.4 63808.7 63819.0 40257.4 40828.0 41104.4 43303.6 43321.8 19868.6 20029.1 20054.7 20058.6 20068.2 12229.1 15163.3 15370.8 15457.8 15461.6 20783.3 20868.2 20950.9 20957.9 20998.8 7472.9 7582.5 7696.9 7702.2 7725.8 999.3 1071.3 1084.8 1098.4 1179.7 35454.8 35554.0 35579.0 35736.6 35948.3 1129.7 1187.2 1196.4 1205.9 1233.2 18383.7 18665.7 18689.8 18869.3 18949.0 476864.0 654573.0 660938.0 662616.0 667551.0 64628.0 65132.0 65158.3 65199.7 65278.0 1316.7 1456.7 1475.7 1485.8 1522.8 34375.5 35615.5 36205.5 36393.5 37049.8 54906.0 55712.7 55724.7 55728.4 55804.7 481078.0 486494.0 486551.0 486613.0 486806.0 29202.6 30274.0 30497.5 31800.5 32392.5 51832.0 52142.0 52194.7 52439.0 52649.0 42937.0 43425.3 43846.6 45532.0 45755.0 43184.0 43727.6 45158.0 45337.8 47622.0 46432.8 47658.5 47719.0 47836.3 47857.3 38610.8 41114.2 41147.0 41695.8 41802.0 30891.7 30941.7 31180.7 31189.2 31379.7 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | [#2] jquery.html Cycles:180 Average:28739.20 Median:3284.28 stddev:76862.01 (2340.3%) stddev-sans-first:77048.24 11:30:53 INFO - PROCESS | 3284 | Values: 430.4 431.1 438.6 442.2 449.3 19041.0 20610.9 21010.0 21097.5 21456.2 20367.5 21071.7 21186.0 21309.6 21327.9 5763.5 5993.9 6006.2 6007.8 6008.2 5607.9 5864.6 5891.7 5895.3 5896.2 5797.7 5852.4 5868.9 5869.7 5870.0 5769.9 5812.8 5813.7 5813.8 5814.4 5191.3 5270.9 5273.1 5294.1 5325.3 24499.8 24677.0 24687.8 25099.6 25212.0 3176.6 3246.4 3322.2 3403.3 3417.3 14841.4 15515.4 15760.8 16008.1 16378.8 1982.4 2025.5 2076.1 2091.5 2102.9 57874.0 74754.3 74893.3 80976.0 82541.0 11042.6 11214.6 11306.1 11528.5 11575.3 15726.9 15736.8 15745.4 15768.1 15802.9 8603.9 8636.0 8774.3 8899.6 8905.6 8428.7 8517.7 8553.6 8612.7 8683.8 6839.6 6942.7 6977.7 7020.7 7035.8 1816.7 1918.8 1935.4 2017.8 2049.3 473.2 1033.3 1035.9 1049.1 1065.5 12293.6 13210.4 13231.9 13242.5 13296.9 476.6 477.1 487.9 494.8 503.1 7067.9 7090.6 7202.9 7221.5 7249.3 388922.0 394373.0 397493.0 538128.0 563370.0 102209.5 105886.0 107227.5 108376.0 110235.4 1028.3 1049.0 1049.7 1062.5 1071.1 18296.2 18758.0 18812.6 18841.7 18991.2 43353.8 43691.8 44076.6 44760.3 44762.6 61942.5 62184.0 62675.1 63313.4 64770.4 8295.6 8846.6 8931.3 8991.6 9183.8 12360.9 13527.1 13564.5 13846.7 13877.4 11507.8 12082.6 12113.3 12153.2 12207.5 11221.3 11400.5 11686.8 11694.8 12064.1 11954.2 11964.5 11997.8 12059.2 12248.7 7996.6 8507.6 8519.4 8605.7 8653.1 11944.3 12003.3 12004.2 12269.1 12430.8 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | [#3] mootools.html Cycles:180 Average:6312.68 Median:4695.79 stddev:3665.22 (78.1%) stddev-sans-first:3657.93 11:30:53 INFO - PROCESS | 3284 | Values: 1536.1 1552.7 1708.8 1776.9 1832.8 9193.5 9878.9 9935.8 9943.8 9944.2 8451.8 9176.3 9329.6 9381.9 9650.8 2435.7 2476.4 2478.2 2486.3 2486.8 2359.6 2439.1 2455.3 2456.0 2458.3 2420.2 2429.8 2479.8 2481.0 2484.4 2340.1 2347.4 2347.8 2363.2 2399.1 2381.5 2385.1 2387.8 2390.6 2402.4 9377.1 9814.5 9857.9 9888.5 9905.7 5402.4 5510.8 5527.4 5528.7 5532.1 3323.9 3397.2 3435.4 3475.0 3507.2 1234.3 1252.9 1268.5 1288.3 1299.2 13199.9 13265.9 13287.3 13335.5 13358.7 4747.6 4762.5 4815.3 4853.0 4959.8 11270.6 11297.9 11310.4 11313.6 11320.7 5537.9 5576.8 5583.0 5584.6 5591.6 4644.0 5001.8 5061.6 5110.7 5122.3 4180.9 4274.8 4425.7 4471.1 4478.4 1386.2 1417.8 1418.2 1426.4 1443.8 1072.0 1074.7 1082.9 1091.7 1094.9 6452.5 6626.0 6656.3 6671.0 6823.1 475.3 475.6 478.8 486.7 496.3 4429.7 4462.9 4519.4 4556.0 4615.2 11646.9 11922.2 12009.5 12036.8 12039.6 11401.5 11966.6 11970.9 11974.2 11981.8 8743.3 9168.7 9171.6 9181.6 9206.5 6616.7 6691.0 6700.3 6818.5 6892.1 10635.4 11153.1 11173.9 11335.7 11441.3 11428.4 11450.9 11453.4 11568.3 11586.4 7552.9 7847.8 7854.1 8264.8 8520.6 9246.4 9545.8 9557.5 9800.2 9813.3 8419.7 8598.4 8647.7 8680.0 8758.6 8216.1 8229.9 8422.2 8505.9 8738.6 7252.9 7412.0 7469.2 7633.4 7638.7 5655.6 5788.3 5873.3 5893.8 5912.7 5752.3 5777.2 5782.0 5837.4 5845.3 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | [#4] prototype.html Cycles:180 Average:4864.83 Median:5512.93 stddev:1424.19 (25.8%) stddev-sans-first:1387.36 11:30:53 INFO - PROCESS | 3284 | Values: 353.9 355.6 358.9 359.3 374.4 5554.2 5562.3 5588.0 5588.9 5591.6 5457.7 5465.9 5508.6 5517.3 5549.5 4199.9 4287.1 4343.2 4347.5 4367.3 4308.5 4326.2 4327.5 4339.5 4384.6 4144.2 4325.9 4342.0 4344.8 4345.3 4141.6 4283.0 4290.7 4314.3 4322.3 3820.8 3828.2 3836.3 3842.1 3844.7 5186.6 5418.3 5445.9 5453.5 5475.4 4944.3 4944.5 4945.5 4964.5 4990.2 5721.5 5729.2 5732.7 5770.9 5838.8 4046.3 4216.5 4218.3 4245.3 4249.0 5996.2 6001.4 6010.3 6015.7 6042.5 4280.6 4297.8 4301.0 4310.4 4344.6 4149.5 4394.9 4397.8 4400.9 4410.5 4901.8 4910.5 4919.6 4921.4 4951.1 5788.0 5796.7 5847.9 5870.2 5877.8 1446.5 1490.7 1495.9 1520.1 1523.4 945.5 962.7 975.8 976.7 991.3 4960.4 4961.9 4977.6 4977.7 5006.7 5188.8 5190.9 5211.9 5226.2 5242.9 4993.3 5438.3 5459.8 5534.6 5608.4 3125.4 3157.3 3183.7 3207.9 3213.4 5908.7 6142.7 6146.8 6163.4 6173.6 6042.2 6043.6 6116.2 6119.7 6123.6 6139.3 6144.2 6149.2 6164.3 6175.4 5736.3 5920.8 5992.2 6000.8 6016.2 5936.6 5941.3 5952.5 5969.3 5999.6 5841.6 6100.2 6115.3 6187.3 6217.4 4226.2 4258.4 4263.4 4264.7 4271.7 5983.2 6049.3 6065.7 6081.3 6091.5 5701.9 5801.7 5814.3 5829.8 5866.9 5809.1 5810.9 5813.4 5840.0 5893.5 5545.9 5764.2 5777.5 5781.5 5840.8 5859.9 5870.3 5880.4 5882.4 5895.8 5725.5 5764.5 5780.8 5783.5 5784.2 11:30:53 INFO - PROCESS | 3284 | 11:30:53 INFO - PROCESS | 3284 | [#5] yui.html Cycles:180 Average:22547.01 Median:22276.22 stddev:32469.95 (145.8%) stddev-sans-first:32520.05 11:30:53 INFO - PROCESS | 3284 | Values: 818.7 827.7 851.6 875.7 915.2 16368.5 16516.7 17316.4 17318.1 17384.1 16545.7 16568.7 16759.8 16768.4 17312.6 737.0 877.3 898.6 901.0 915.6 885.7 886.6 886.9 889.2 889.3 917.2 923.6 923.6 924.8 927.8 924.0 924.6 924.9 925.3 926.1 680.9 681.7 681.7 683.1 683.8 16746.9 16756.0 16862.8 16886.3 16947.2 15572.3 15736.5 15798.8 15948.3 16110.0 12913.2 13405.3 13555.7 13575.5 13593.1 4010.8 4088.5 4145.7 4156.8 4229.4 77504.0 77819.6 78034.2 79237.6 80044.2 12410.8 12487.9 12492.1 12625.8 12761.9 22057.1 22185.0 22220.9 22248.8 22303.7 8688.0 8755.4 8776.5 8780.0 9343.0 5121.5 5174.8 5294.2 5302.0 5321.0 3614.7 3766.0 3780.5 3906.7 4104.4 699.2 700.1 703.9 705.6 708.4 703.7 732.4 799.4 812.6 823.4 10677.8 11070.2 11175.6 11372.2 11637.9 781.5 814.0 814.9 821.1 825.2 5227.4 5399.6 5405.6 5435.1 5483.7 93955.5 122894.0 129360.4 130059.3 131241.3 111484.4 114992.4 116123.0 116181.4 116506.0 61575.0 97651.0 100104.5 100475.0 103140.0 10134.9 10184.9 10226.8 10338.2 10416.6 46986.5 48042.0 57949.0 58847.1 58915.0 76623.0 78008.0 78013.2 78094.0 79353.2 14630.6 14681.2 14743.6 14770.3 14786.5 16626.0 16691.1 16769.5 16779.7 16844.9 14443.3 14449.2 14688.3 14697.9 14811.5 15277.8 15367.6 15406.7 15991.2 16097.3 13652.7 14111.4 14552.6 14639.6 15314.1 12219.9 12604.6 12744.7 12849.9 12878.9 11870.5 11902.8 12040.9 12217.9 12330.9 11:30:53 INFO - PROCESS | 3284 | -------- Summary: end -------- 11:30:53 INFO - PROCESS | 3284 | 11:30:54 INFO - TEST-INFO | 3284: exit 0 11:30:54 INFO - TEST-OK | dromaeo_css | took 1112105ms 11:30:54 INFO - TEST-START | kraken 11:30:54 INFO - operating with platform_type : linux_ 11:30:54 INFO - Initialising browser for kraken test... 11:30:54 INFO - TEST-INFO | started process 3484 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp34QXXN/profile http://localhost:60058/getInfo.html) 11:30:58 INFO - PROCESS | 3484 | __metrics Screen width/height:1600/1200 11:30:58 INFO - PROCESS | 3484 | colorDepth:24 11:30:58 INFO - PROCESS | 3484 | Browser inner width/height: 1024/697 11:30:58 INFO - PROCESS | 3484 | __metrics 11:30:59 INFO - PROCESS | 3484 | 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" 11:30:59 INFO - PROCESS | 3484 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:30:59 INFO - PROCESS | 3484 | console.error: PushService: 11:30:59 INFO - PROCESS | 3484 | stateChangeProcessEnqueue: Error transitioning state 11:30:59 INFO - PROCESS | 3484 | UnknownError 11:31:00 INFO - TEST-INFO | 3484: exit 0 11:31:00 INFO - Browser initialized. 11:31:00 INFO - Running cycle 1/1 for kraken test... 11:31:00 INFO - TEST-INFO | started process 3571 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp34QXXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:11 INFO - PROCESS | 3571 | RSS: Main: 143532032 11:31:11 INFO - PROCESS | 3571 | 11:31:14 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60058/tests/kraken/driver.html?testName=audio-beat-detection) 11:31:15 INFO - PROCESS | 3571 | RSS: Main: 198692864 11:31:15 INFO - PROCESS | 3571 | 11:31:17 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60058/tests/kraken/driver.html?testName=audio-dft) 11:31:18 INFO - PROCESS | 3571 | RSS: Main: 183578624 11:31:18 INFO - PROCESS | 3571 | 11:31:21 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60058/tests/kraken/driver.html?testName=audio-fft) 11:31:21 INFO - PROCESS | 3571 | RSS: Main: 274837504 11:31:21 INFO - PROCESS | 3571 | 11:31:23 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60058/tests/kraken/driver.html?testName=audio-oscillator) 11:31:24 INFO - PROCESS | 3571 | RSS: Main: 179847168 11:31:24 INFO - PROCESS | 3571 | 11:31:26 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60058/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:31:27 INFO - PROCESS | 3571 | RSS: Main: 179126272 11:31:27 INFO - PROCESS | 3571 | 11:31:30 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60058/tests/kraken/driver.html?testName=imaging-darkroom) 11:31:30 INFO - PROCESS | 3571 | RSS: Main: 215089152 11:31:30 INFO - PROCESS | 3571 | 11:31:34 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60058/tests/kraken/driver.html?testName=imaging-desaturate) 11:31:34 INFO - PROCESS | 3571 | RSS: Main: 213680128 11:31:34 INFO - PROCESS | 3571 | 11:31:37 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60058/tests/kraken/driver.html?testName=json-parse-financial) 11:31:38 INFO - PROCESS | 3571 | RSS: Main: 214085632 11:31:38 INFO - PROCESS | 3571 | 11:31:39 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60058/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:31:40 INFO - PROCESS | 3571 | RSS: Main: 175607808 11:31:40 INFO - PROCESS | 3571 | 11:31:41 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:31:42 INFO - PROCESS | 3571 | RSS: Main: 183263232 11:31:42 INFO - PROCESS | 3571 | 11:31:44 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:31:44 INFO - PROCESS | 3571 | RSS: Main: 188923904 11:31:44 INFO - PROCESS | 3571 | 11:31:47 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:31:48 INFO - PROCESS | 3571 | RSS: Main: 197058560 11:31:48 INFO - PROCESS | 3571 | 11:31:50 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:31:51 INFO - PROCESS | 3571 | RSS: Main: 194080768 11:31:51 INFO - PROCESS | 3571 | 11:31:52 INFO - PROCESS | 3571 | Cycle 1(1): loaded http://localhost:60058/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60058/tests/kraken/driver.html?testName=ai-astar) 11:31:53 INFO - PROCESS | 3571 | RSS: Main: 244535296 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | __start_tp_report 11:31:53 INFO - PROCESS | 3571 | _x_x_mozilla_page_load 11:31:53 INFO - PROCESS | 3571 | _x_x_mozilla_page_load_details 11:31:53 INFO - PROCESS | 3571 | |i|pagename|runs| 11:31:53 INFO - PROCESS | 3571 | |0;ai-astar;111;109;121;110;110;110;110;109;109;109 11:31:53 INFO - PROCESS | 3571 | |1;audio-beat-detection;145;118;138;175;121;140;145;120;175;121 11:31:53 INFO - PROCESS | 3571 | |2;audio-dft;163;160;159;161;160;160;161;161;162;159 11:31:53 INFO - PROCESS | 3571 | |3;audio-fft;73;74;76;171;76;75;74;74;179;74 11:31:53 INFO - PROCESS | 3571 | |4;audio-oscillator;84;84;146;83;83;82;139;82;83;148 11:31:53 INFO - PROCESS | 3571 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;117;117;116 11:31:53 INFO - PROCESS | 3571 | |6;imaging-darkroom;146;146;145;145;145;146;145;145;146;146 11:31:53 INFO - PROCESS | 3571 | |7;imaging-desaturate;94;94;95;95;95;95;95;95;95;95 11:31:53 INFO - PROCESS | 3571 | |8;json-parse-financial;53;50;56;50;50;50;51;50;50;50 11:31:53 INFO - PROCESS | 3571 | |9;json-stringify-tinderbox;59;61;60;59;60;59;59;60;61;60 11:31:53 INFO - PROCESS | 3571 | |10;stanford-crypto-aes;75;82;73;76;84;73;78;72;77;72 11:31:53 INFO - PROCESS | 3571 | |11;stanford-crypto-ccm;129;128;124;128;118;120;117;121;153;122 11:31:53 INFO - PROCESS | 3571 | |12;stanford-crypto-pbkdf2;155;151;149;151;149;150;150;157;150;149 11:31:53 INFO - PROCESS | 3571 | |13;stanford-crypto-sha256-iterative;60;60;62;60;61;65;61;61;62;59 11:31:53 INFO - PROCESS | 3571 | __end_tp_report 11:31:53 INFO - PROCESS | 3571 | __start_cc_report 11:31:53 INFO - PROCESS | 3571 | _x_x_mozilla_cycle_collect,1694 11:31:53 INFO - PROCESS | 3571 | __end_cc_report 11:31:53 INFO - PROCESS | 3571 | __startTimestamp1475692313234__endTimestamp 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | ------- Summary: start ------- 11:31:53 INFO - PROCESS | 3571 | Number of tests: 14 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#0] ai-astar Cycles:10 Average:110.80 Median:110.00 stddev:3.65 (3.3%) stddev-sans-first:3.87 11:31:53 INFO - PROCESS | 3571 | Values: 111.0 109.0 121.0 110.0 110.0 110.0 110.0 109.0 109.0 109.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:142.50 stddev:21.37 (15.0%) stddev-sans-first:22.58 11:31:53 INFO - PROCESS | 3571 | Values: 145.0 118.0 138.0 175.0 121.0 140.0 145.0 120.0 175.0 121.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.26 (0.8%) stddev-sans-first:1.00 11:31:53 INFO - PROCESS | 3571 | Values: 163.0 160.0 159.0 161.0 160.0 160.0 161.0 161.0 162.0 159.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#3] audio-fft Cycles:10 Average:94.60 Median:74.00 stddev:42.43 (57.3%) stddev-sans-first:44.27 11:31:53 INFO - PROCESS | 3571 | Values: 73.0 74.0 76.0 171.0 76.0 75.0 74.0 74.0 179.0 74.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#4] audio-oscillator Cycles:10 Average:101.40 Median:83.00 stddev:29.72 (35.8%) stddev-sans-first:30.85 11:31:53 INFO - PROCESS | 3571 | Values: 84.0 84.0 146.0 83.0 83.0 82.0 139.0 82.0 83.0 148.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 11:31:53 INFO - PROCESS | 3571 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 11:31:53 INFO - PROCESS | 3571 | Values: 146.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 11:31:53 INFO - PROCESS | 3571 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:50.00 stddev:2.00 (4.0%) stddev-sans-first:1.99 11:31:53 INFO - PROCESS | 3571 | Values: 53.0 50.0 56.0 50.0 50.0 50.0 51.0 50.0 50.0 50.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.79 (1.3%) stddev-sans-first:0.78 11:31:53 INFO - PROCESS | 3571 | Values: 59.0 61.0 60.0 59.0 60.0 59.0 59.0 60.0 61.0 60.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:76.50 stddev:4.16 (5.4%) stddev-sans-first:4.39 11:31:53 INFO - PROCESS | 3571 | Values: 75.0 82.0 73.0 76.0 84.0 73.0 78.0 72.0 77.0 72.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:126.00 stddev:10.39 (8.2%) stddev-sans-first:10.97 11:31:53 INFO - PROCESS | 3571 | Values: 129.0 128.0 124.0 128.0 118.0 120.0 117.0 121.0 153.0 122.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:150.50 stddev:2.73 (1.8%) stddev-sans-first:2.50 11:31:53 INFO - PROCESS | 3571 | Values: 155.0 151.0 149.0 151.0 149.0 150.0 150.0 157.0 150.0 149.0 11:31:53 INFO - PROCESS | 3571 | 11:31:53 INFO - PROCESS | 3571 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.66 (2.7%) stddev-sans-first:1.72 11:31:53 INFO - PROCESS | 3571 | Values: 60.0 60.0 62.0 60.0 61.0 65.0 61.0 61.0 62.0 59.0 11:31:53 INFO - PROCESS | 3571 | -------- Summary: end -------- 11:31:53 INFO - PROCESS | 3571 | 11:31:54 INFO - TEST-INFO | 3571: exit 0 11:31:54 INFO - TEST-OK | kraken | took 59892ms 11:31:54 INFO - SUITE-END | took 1171s 11:31:54 INFO - Completed test suite (00:19:32) 11:31:54 INFO - javascript benchmark 11:31:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3240.7245384142348, 3267.215868191633, 3280.294736631624, 3308.6360500344945, 3331.850066746996, 5617.971910140449, 5662.263905692254, 5689.914651280229, 5703.600747947635, 5707.286589176353, 5499.670019798815, 5531.917021244684, 5564.582656300783, 5570.359408668004, 5592.3009623797025, 4989.077020751172, 5069.721165335905, 5097.464766199551, 5114.0, 5142.3829140503, 5055.9241611375855, 5117.1556693145585, 5138.9743051284695, 5143.485651434845, 5153.407358153804, 5033.446320904697, 5047.369078865142, 5056.696598204101, 5124.743762811863, 5129.2562578426105, 5143.8456846294675, 5177.068127737006, 5178.14560597501, 5224.63427560071, 5237.6071794615555, 5064.594832413435, 5139.817841896384, 5199.792008319662, 5214.478552144793, 5239.607029472805, 5411.972940135313, 5511.807086751945, 5516.14499752539, 5544.390117087117, 5584.106542953108, 5884.323302820145, 5907.822765317047, 6007.279126504847, 6032.245969253843, 6032.909506357408, 5862.560307976919, 6007.909881351783, 6021.0, 6027.638341699469, 6028.517718582503, 5607.439256074401, 5634.3520495143175, 5638.210650508891, 5665.0, 5729.312482502127, 6213.65824879627, 6215.06773983897, 6215.316315205414, 6216.160818289563, 6219.564630475823, 5802.21670074543, 5928.288605367384, 5973.432523910222, 6003.339632640406, 6003.8198854034445, 6033.909491357633, 6099.0, 6101.079068467482, 6106.107606160077, 6112.1995365628145, 6081.331053584102, 6089.5737298388685, 6089.634621922699, 6101.450869421773, 6110.480609148272, 5802.390748971297, 5911.172435858984, 6040.124181993587, 6089.725962331706, 6092.7562897484495, 4586.289125185602, 4590.770461476913, 4601.769911504478, 4614.238650622677, 4615.699979501322, 4077.408775727503, 4109.630133288018, 4119.402686610485, 4124.896877578025, 4145.005198752338, 5549.972250138804, 5761.049426844607, 5778.422157784188, 5779.739911703983, 5788.507976822018, 6076.483498902643, 6127.769884593388, 6148.508119350441, 6151.661658608819, 6159.23009623797, 5847.707614619337, 5851.765929362863, 5855.677937713466, 5855.912161317584, 5881.411858814085, 5088.236764485357, 5166.5866730661455, 5270.077736396115, 5272.367315922113, 5274.156135018456, 6081.695915204311, 6178.7219575119225, 6205.875882482415, 6208.1308616793685, 6210.099535567408, 6024.457798798129, 6028.668423236764, 6032.0, 6038.094285857156, 6077.696115194134, 6027.758889644365, 6117.143599895929, 6125.418593525838, 6180.938190618036, 6182.783602573888, 5880.088586269135, 5963.970180149072, 5965.28416590012, 5967.582269241111, 5991.700414979321, 6079.756809727562, 6081.878362432815, 6084.939150608438, 6085.786997455068, 6094.75620975156, 5711.0, 5777.3356064051495, 5940.613860099253, 5940.990031694536, 5941.0, 5789.189513468202, 5860.531157507304, 5860.970695146497, 5891.734871930859, 5893.469587737124, 5774.047282198559, 5777.133429985536, 5779.768809247751, 5782.768689252551, 5817.6509409433875, 5750.683712395691, 5804.796832110856, 5813.3895940925595, 5834.358220595646, 5887.116932460165, 5683.374828768915, 5686.2607860977805, 5687.77248910039, 5718.370979192368, 5719.771209151588], "value": 5554.122803166665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.904315787895, 618.7370367593771, 643.1478291264076, 643.4787821864289, 657.3360905485458, 37518.0, 46977.76511117444, 48111.31610393628, 48125.27812082813, 50982.74508627452, 49322.260166097534, 49724.751376243155, 49790.253146202835, 49855.750721246346, 49858.252126218045, 8534.914650853505, 9185.265178785714, 9352.81294374112, 9358.532073396336, 11057.11543076552, 10920.18098642601, 11374.601888933888, 11380.203385763, 11386.60146894859, 11388.088952883749, 11373.545058197704, 11436.599719009837, 11437.542498300058, 11446.0, 11451.25566838153, 11336.036436902874, 11372.976432121108, 11377.431128443544, 11379.544818207261, 11387.430628468626, 9195.08049195082, 9477.099675530813, 9483.099105584959, 9766.462844543546, 9782.070703283176, 45366.31950520745, 45848.3122753159, 45969.54030459686, 45995.54004459946, 45997.310040349425, 3921.431392448079, 4087.9591204087874, 4264.23243816113, 4273.423087883127, 4619.122366750306, 34279.0, 34286.48570271448, 34361.82819085888, 34664.30671386584, 35269.82365088184, 4566.520515345907, 4690.273007683814, 4705.458872229703, 4727.858164255078, 4789.042191561702, 244595.0, 248866.0, 252559.0, 252675.73662131754, 253276.0, 63393.0, 63742.0, 63803.36196637974, 63808.68095659492, 63819.0, 40257.396139057935, 40828.0, 41104.38343424851, 43303.56696433058, 43321.783391083474, 19868.60262794736, 20029.09869055896, 20054.699179512318, 20058.59882802365, 20068.197272109242, 12229.143959922898, 15163.317650705523, 15370.846291537166, 15457.768133478006, 15461.613459663373, 20783.272585459436, 20868.165273389197, 20950.89524552398, 20957.89521052385, 20998.790012099988, 7472.887088567229, 7582.54504729718, 7696.884546731803, 7702.191269916578, 7725.768226953201, 999.3054826895238, 1071.2501249125644, 1084.8426978088132, 1098.4452851310198, 1179.728662407651, 35454.8227258862, 35554.0, 35579.0, 35736.64263357385, 35948.281034379164, 1129.7232178116249, 1187.1927089578996, 1196.407778149804, 1205.9035277177804, 1233.216907263876, 18383.72424413635, 18665.720014199796, 18689.81310186908, 18869.339573114874, 18949.0, 476864.0, 654573.0, 660938.0, 662616.0, 667551.0, 64628.0, 65132.0, 65158.34841651618, 65199.674001630636, 65278.0, 1316.6839958410105, 1456.6795305032877, 1475.6679747056826, 1485.762278035509, 1522.824875139351, 34375.48436773501, 35615.46576801298, 36205.45691814572, 36393.514027203324, 37049.814750926074, 54906.0, 55712.72143639418, 55724.72137639286, 55728.44271557395, 55804.720976396486, 481078.0, 486494.0, 486551.0, 486613.0, 486806.0, 29202.56196157017, 30274.0, 30497.54253686241, 31800.522992154674, 32392.514112288805, 51832.0, 52142.0, 52194.739026304625, 52439.0, 52649.0, 42937.0, 43425.3486197701, 43846.561534385524, 45532.0, 45755.0, 43184.0, 43727.56272437362, 45157.96789144562, 45337.77331113455, 47622.0, 46432.7678361606, 47658.523414766794, 47719.0, 47836.28245576249, 47857.282140767216, 38610.80694596509, 41114.17771644491, 41147.0, 41695.7915210422, 41802.0, 30891.69108308888, 30941.69058309478, 31180.688193118687, 31189.220269492536, 31379.686203137677], "value": 21909.123817670275, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.39099673961346, 431.1351072125723, 438.6381235480728, 442.230518897119, 449.3349842233494, 19041.0, 20610.89694551527, 21010.0, 21097.47256318593, 21456.249031283907, 20367.490812729688, 21071.683924741104, 21186.0, 21309.57380852382, 21327.893360533177, 5763.452472015152, 5993.850153746148, 6006.189164462785, 6007.8197654070445, 6008.24896887889, 5607.943920560803, 5864.618799778001, 5891.7054147292465, 5895.292564892219, 5896.204012458327, 5797.65214087154, 5852.414758524156, 5868.9413105868825, 5869.677167755772, 5869.9706501467435, 5769.913451298234, 5812.796552120677, 5813.6802475863815, 5813.79651712188, 5814.360420353758, 5191.299174611417, 5270.92093618596, 5273.1299335609965, 5294.100002999499, 5325.254464374991, 24499.755002449925, 24677.0, 24687.753122468905, 25099.62350564743, 25212.0, 3176.5870436843293, 3246.3507298540385, 3322.2026713588566, 3403.268297316064, 3417.3165366926714, 14841.406343746237, 15515.379384824593, 15760.842391576052, 16008.119553424556, 16378.754318685229, 1982.3755517012096, 2025.5341271507489, 2076.096897849425, 2091.5084955035554, 2102.9158833646634, 57874.0, 74754.25245747528, 74893.25106748917, 80976.0, 82541.0, 11042.613508527164, 11214.607488737855, 11306.095512358992, 11528.481218345194, 11575.305481671126, 15726.9213653931, 15736.763948540782, 15745.370185192693, 15768.132752698712, 15802.920985395229, 8603.913960860436, 8635.956820215859, 8774.298056155492, 8899.599518021705, 8905.643774249087, 8428.74713758576, 8517.701880434155, 8553.615087321086, 8612.741617751477, 8683.826323473495, 6839.623820689811, 6942.7222911084, 6977.6511174441075, 7020.684069216897, 7035.753748618774, 1816.747213729175, 1918.788933217345, 1935.4097000414727, 2017.7982201779705, 2049.282751037125, 473.1884817537939, 1033.2618560813753, 1035.8653375061344, 1049.1292227451197, 1065.4619417194267, 12293.56972505958, 13210.405531751094, 13231.867681323063, 13242.470301187845, 13296.933515332556, 476.6234674607091, 477.1054273237679, 487.8560824556693, 494.83175720254565, 503.07434320849814, 7067.858642827115, 7090.610016449042, 7202.855942881218, 7221.53060051095, 7249.311315425026, 388922.0, 394373.0, 397493.0, 538128.0, 563370.0, 102209.48895255476, 105886.0, 107227.46386268019, 108376.0, 110235.44882275537, 1028.3264461777549, 1049.0296475759892, 1049.7008352619466, 1062.4740753327153, 1071.0681706915034, 18296.176672050056, 18758.0, 18812.623747525246, 18841.71737423967, 18991.24035038583, 43353.783231083646, 43691.78154109209, 44076.55923440724, 44760.328595071755, 44762.55237447584, 61942.45143871259, 62184.0, 62675.05987410284, 63313.36686633075, 64770.35229647832, 8295.585220738818, 8846.646134154562, 8931.330150238628, 8991.640334386551, 9183.816323673624, 12360.938195308965, 13527.120737152054, 13564.525241616497, 13846.65383365424, 13877.444902203799, 11507.769844603228, 12082.637520874388, 12113.333766642567, 12153.210041347284, 12207.45066471993, 11221.270617409842, 11400.486978086172, 11686.766264674488, 11694.766104678029, 12064.095192860395, 11954.222975506564, 11964.521419143484, 11997.82003269969, 12059.21615095016, 12248.693782655504, 7996.56018918965, 8507.617157227816, 8519.361047921308, 8605.698800541952, 8653.091425400495, 11944.343061131722, 12003.339816310187, 12004.2197257178, 12269.079819013432, 12430.81353779676], "value": 9378.673764329293, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1536.1474381718147, 1552.728272552303, 1708.8034875989265, 1776.9289228430864, 1832.7525784019163, 9193.494357810327, 9878.851817222738, 9935.751606209831, 9943.801123977517, 9944.154746846527, 8451.830963380744, 9176.265898728103, 9329.580168892398, 9381.859272110923, 9650.806983860319, 2435.719892212396, 2476.418041760185, 2478.1821998740375, 2486.3038349262238, 2486.8383555068863, 2359.6460530920326, 2439.134107391877, 2455.3247856839334, 2455.9754402456015, 2458.3485376375274, 2420.213688126289, 2429.842060266086, 2479.838810477313, 2481.0323973650293, 2484.4037431016604, 2340.0756701103055, 2347.389678683546, 2347.788699017088, 2363.196513185508, 2399.1123284384716, 2381.4641705616273, 2385.1294277588727, 2387.8089752819733, 2390.6294524348755, 2402.423418379594, 9377.1091746284, 9814.46020468874, 9857.852132218022, 9888.456134912576, 9905.653302134391, 5402.37872644647, 5510.779568817243, 5527.419620939824, 5528.695921724344, 5532.114861622162, 3323.88366407177, 3397.167693914982, 3435.3644575753315, 3475.044362800225, 3507.2108775525303, 1234.2717796500108, 1252.9436175372039, 1268.5052829396543, 1288.2978776566792, 1299.1815156451376, 13199.86800131996, 13265.933670331586, 13287.269200193889, 13335.533091359943, 13358.732825343439, 4747.620190384761, 4762.547557982056, 4815.3499277597775, 4852.951470485321, 4959.801607935642, 11270.605528806616, 11297.943510282561, 11310.377929213808, 11313.604023859125, 11320.71698207535, 5537.916931246034, 5576.804811831566, 5583.022970980062, 5584.63699859516, 5591.580631452657, 4643.955110100201, 5001.824936127218, 5061.620378471629, 5110.744462776846, 5122.282880396748, 4180.895477613084, 4274.829006839754, 4425.690201685916, 4471.128130014681, 4478.440194975628, 1386.202933313349, 1417.7660685986696, 1418.156197062746, 1426.3652674559928, 1443.8483959184343, 1071.978560428787, 1074.6722249713912, 1082.86464191976, 1091.6561283195767, 1094.8905109488987, 6452.4515416189215, 6626.0, 6656.301088385747, 6671.0, 6823.078884350648, 475.28944228378793, 475.59099174709723, 478.8204423341247, 486.68365562384025, 496.3150851824531, 4429.734215947054, 4462.888427789331, 4519.435070616173, 4556.0, 4615.215413379734, 11646.88353116458, 11922.225055371373, 12009.459574319177, 12036.759264814655, 12039.578614748441, 11401.486933087865, 11966.58116965902, 11970.940145299392, 11974.221675591232, 11981.760364792655, 8743.300535957109, 9168.724938251864, 9171.58727857248, 9181.632734690538, 9206.493642849708, 6616.66916654175, 6691.0, 6700.2964688708935, 6818.454523638196, 6892.138482689664, 10635.415052172204, 11153.051990580892, 11173.944130279297, 11335.659930202108, 11441.256318339281, 11428.428578570873, 11450.885491145315, 11453.427328633701, 11568.305901645928, 11586.42067896585, 7552.9244707552225, 7847.84304313922, 7854.057513098464, 8264.793380165544, 8520.573971301534, 9246.398984066014, 9545.809083818423, 9557.52212389364, 9800.215982721222, 9813.26400519978, 8419.747407577783, 8598.398112132341, 8647.697330593399, 8679.956600216958, 8758.649654013769, 8216.137305582828, 8229.876551851607, 8422.157784221528, 8505.872411913702, 8738.606762695821, 7252.927470725225, 7412.0, 7469.178390377163, 7633.389328853567, 7638.732644357421, 5655.632383895034, 5788.27646544182, 5873.295204575478, 5893.823185304448, 5912.733926973383, 5752.28096487939, 5777.24895763548, 5782.045962416154, 5837.445442682938, 5845.26991980199], "value": 4935.046485281681, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.87791212031846, 355.57508777011475, 358.88515674984, 359.28860855506116, 374.42712649646035, 5554.194641776945, 5562.276904002484, 5587.960394456025, 5588.888222235553, 5591.580631452642, 5457.727113644321, 5465.9180112298345, 5508.614396992202, 5517.2551705519745, 5549.472800083986, 4199.887029937069, 4287.1425714857, 4343.174796788616, 4347.543507931669, 4367.3448982652535, 4308.504521979981, 4326.178026175033, 4327.480702315726, 4339.500957389893, 4384.583464570861, 4144.171165766858, 4325.891852703676, 4341.978290108561, 4344.804483798221, 4345.3047512398025, 4141.585841415849, 4282.9999999999845, 4290.69965102443, 4314.309710446313, 4322.286822674255, 3820.789856557888, 3828.1769419574925, 3836.3094642964256, 3842.1163132479405, 3844.692424606025, 5186.5850731941355, 5418.3450109446485, 5445.945540544583, 5453.509184173404, 5475.370332411784, 4944.258361245806, 4944.456109827916, 4945.505449455061, 4964.528369804862, 4990.228468893563, 5721.482547679049, 5729.195303479432, 5732.742026608813, 5770.884582308372, 5838.766449342022, 4046.2514434829354, 4216.51510076342, 4218.261804184255, 4245.299525578276, 4249.0, 5996.19051428056, 6001.369856165084, 6010.338862725097, 6015.729292181863, 6042.45917291088, 4280.5505421930875, 4297.82808687649, 4301.0, 4310.353446982977, 4344.587264209895, 4149.543550209474, 4394.868153955386, 4397.824087036547, 4400.911981760347, 4410.514843367227, 4901.828436004723, 4910.533499317559, 4919.631027672939, 4921.434035085939, 4951.10880041596, 5788.0, 5796.739146738407, 5847.88890110886, 5870.178175055496, 5877.764889404461, 1446.5443385333585, 1490.6646004648867, 1495.8878084143732, 1520.065159926637, 1523.405871710034, 945.5225111318878, 962.6582563189967, 975.8095123948771, 976.7497603068084, 991.3457118302031, 4960.429550601655, 4961.90076198474, 4977.552020318117, 4977.676451030671, 5006.69959802413, 5188.81839135636, 5190.8961820764125, 5211.895762084737, 5226.2230841514065, 5242.895142097137, 4993.251012348177, 5438.265834112344, 5459.754311056012, 5534.584906132056, 5608.355039170548, 3125.406172827201, 3157.3369592385857, 3183.7293830024246, 3207.8716851326153, 3213.4055199787645, 5908.734106965197, 6142.692865356804, 6146.846328841725, 6163.3836616338, 6173.629582225081, 6042.154098426224, 6043.637381757108, 6116.204893363834, 6119.724612392453, 6123.6019658722935, 6139.263288405331, 6144.17053697763, 6149.2313460817395, 6164.291106522717, 6175.382461753788, 5736.340320862987, 5920.763169473173, 5992.161097446274, 6000.819975400745, 6016.2479690038745, 5936.5844390892225, 5941.287045554561, 5952.5237980960555, 5969.343372228965, 5999.580029398075, 5841.649501029951, 6100.206973093469, 6115.327313995546, 6187.257529096537, 6217.440430361108, 4226.239276930182, 4258.425112609758, 4263.3818096375235, 4264.722793018444, 4271.700980931305, 5983.192269043798, 6049.30433000193, 6065.695875386813, 6081.300650425258, 6091.451769340781, 5701.859628074485, 5801.680907550125, 5814.30228372598, 5829.7959571416495, 5866.882662346644, 5809.070548712186, 5810.854728631818, 5813.3605303417435, 5840.014395105514, 5893.469587737124, 5545.944540554704, 5764.184281951172, 5777.45114214149, 5781.4507621775865, 5840.838889610871, 5859.912101318569, 5870.26621672291, 5880.382559831156, 5882.441168089024, 5895.7618900031475, 5725.51333136688, 5764.452377024176, 5780.826575202751, 5783.508401785895, 5784.161296611884], "value": 4415.146348650916, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.6725309876052, 827.6565225431447, 851.5784686580143, 875.6759998800444, 915.2220612479393, 16368.508944731662, 16516.66966660666, 17316.393926212586, 17318.134093295346, 17384.13079346034, 16545.66908661826, 16568.66862662746, 16759.83240167598, 16768.41310554131, 17312.56718582036, 737.0455260437719, 877.3332267476732, 898.5911410308327, 900.9864852027225, 915.5651065743751, 885.663447889801, 886.6231851463135, 886.9246114080311, 889.2485849457198, 889.3241136736095, 917.2020342302206, 923.5613083785215, 923.6028507741669, 924.7872989212485, 927.8422668146432, 923.958421871014, 924.5793164110381, 924.8890133184027, 925.2967744514184, 926.0878035135386, 680.9285025072346, 681.6762038031944, 681.6898311268411, 683.0505597219868, 683.7572661705123, 16746.916265418597, 16756.0, 16862.83137168625, 16886.324547018103, 16947.237374318185, 15572.299246533934, 15736.527904162893, 15798.763018554731, 15948.2823272953, 16110.0, 12913.225206487547, 13405.329733513285, 13555.728885422237, 13575.45698172072, 13593.116447430886, 4010.7794071326066, 4088.509378874554, 4145.730527515732, 4156.8129434175535, 4229.407882896397, 77504.0, 77819.61090194626, 78034.21965780326, 79237.60381198114, 80044.19955800484, 12410.75178496443, 12487.875121248853, 12492.06309526771, 12625.810612840814, 12761.93619031899, 22057.11771529153, 22185.0, 22220.888895555418, 22248.77751222467, 22303.665445018014, 8688.0, 8755.430896991675, 8776.473411595325, 8779.956100219459, 9342.95328523353, 5121.513456221653, 5174.844754657366, 5294.232336311214, 5302.045631786315, 5321.042212401805, 3614.6566076222716, 3766.0208345829733, 3780.4518344839844, 3906.746061505993, 4104.384342348611, 699.1854489519652, 700.0863872646246, 703.8873780195145, 705.5590256089944, 708.4226355520235, 703.7466512055656, 732.4433430592787, 799.3605115907251, 812.5571563497908, 823.4153750836973, 10677.78644427107, 11070.225084244026, 11175.552977880794, 11372.203945723886, 11637.941810291064, 781.4607920534813, 813.9633716482773, 814.8777683347427, 821.1214001018972, 825.1865606800108, 5227.372715274191, 5399.568034557226, 5405.594580406485, 5435.130379139318, 5483.670979741229, 93955.53022234845, 122894.0, 129360.35319823341, 130059.34970325087, 131241.34379328234, 111484.4425777866, 114992.42503787427, 116123.0, 116181.41909290568, 116506.0, 61575.0, 97651.0, 100104.4994775036, 100475.0, 103140.0, 10134.898651013396, 10184.885498463758, 10226.846597301195, 10338.224633152695, 10416.635417760343, 46986.530134698216, 48042.0, 57949.0, 58847.11328404259, 58915.0, 76623.0, 78008.0, 78013.2198678006, 78094.0, 79353.20646793458, 14630.561083167522, 14681.19253441071, 14743.557693269218, 14770.33533490974, 14786.48247311382, 16626.0, 16691.08199049064, 16769.496915092568, 16779.748303775697, 16844.915775421043, 14443.277836107942, 14449.205293708947, 14688.339024743698, 14697.92651036738, 14811.481598144013, 15277.805406911191, 15367.61580960485, 15406.691866162839, 15991.200439977722, 16097.275622596777, 13652.65868353299, 14111.364988575333, 14552.563423097325, 14639.560813175622, 15314.081155130727, 12219.87780122223, 12604.55884044054, 12744.745105097662, 12849.87150128524, 12878.871211288142, 11870.465829037887, 11902.761944761229, 12040.939795300967, 12217.877821221675, 12330.938345308216], "value": 8292.683372993351, "unit": "score"}], "name": "dromaeo_css", "value": 7686.457535530215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 121.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 138.0, 175.0, 121.0, 140.0, 145.0, 120.0, 175.0, 121.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 159.0, 161.0, 160.0, 160.0, 161.0, 161.0, 162.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 76.0, 171.0, 76.0, 75.0, 74.0, 74.0, 179.0, 74.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 146.0, 83.0, 83.0, 82.0, 139.0, 82.0, 83.0, 148.0], "value": 101.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 56.0, 50.0, 50.0, 50.0, 51.0, 50.0, 50.0, 50.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0, 61.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 73.0, 76.0, 84.0, 73.0, 78.0, 72.0, 77.0, 72.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 128.0, 124.0, 128.0, 118.0, 120.0, 117.0, 121.0, 153.0, 122.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 151.0, 149.0, 151.0, 149.0, 150.0, 150.0, 157.0, 150.0, 149.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 62.0, 60.0, 61.0, 65.0, 61.0, 61.0, 62.0, 59.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1489.3999999999999, "alertThreshold": 2.0}]} 11:31:54 INFO - Return code: 0 11:31:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:31:55 INFO - # TBPL SUCCESS # 11:31:55 INFO - Running post-action listener: _resource_record_post_action 11:31:55 INFO - [mozharness: 2016-10-05 18:31:55.035214Z] Finished run-tests step (success) 11:31:55 INFO - Running post-run listener: _resource_record_post_run 11:31:55 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 291446784; Read time: 60; Write time: 196804 11:31:55 INFO - TinderboxPrint: CPU usage
12.9% 11:31:55 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 60 11:31:55 INFO - TinderboxPrint: I/O write bytes / time
291,446,784 / 196,804 11:31:55 INFO - TinderboxPrint: CPU idle
8,245.2 (87.1%) 11:31:55 INFO - TinderboxPrint: CPU user
1,179.7 (12.5%) 11:31:55 INFO - TinderboxPrint: Swap in / out
0 / 0 11:31:55 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70074368; Read time: 0; Write time: 71392 11:31:55 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 221372416; Read time: 12; Write time: 125412 11:31:55 INFO - Running post-run listener: _upload_blobber_files 11:31:55 INFO - Blob upload gear active. 11:31:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:31:55 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 11:31:55 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'] 11:31:55 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 11:31:56 INFO - (blobuploader) - INFO - Open directory for files ... 11:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:31:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:56 INFO - (blobuploader) - INFO - Done attempting. 11:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:31:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:58 INFO - (blobuploader) - INFO - Done attempting. 11:31:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:31:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:32:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:02 INFO - (blobuploader) - INFO - Done attempting. 11:32:02 INFO - (blobuploader) - INFO - Iteration through files over. 11:32:02 INFO - Return code: 0 11:32:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:32:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:32:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46daf6c50c319a082331e94944d76c0ea274755fc257ff7e3bed4deec3fa4930311dc45704e2e0f0a6b82af1cdfa02b574a90299e0db5468a84e6a09415d63df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9532f833308f91ff74fc76ea7c7fa26b5a3b4ca62b6d5c0f5667c3ebaced33492f11be9328c17fbed7c7ddff3ac4c1d743ff2a4408f04a446e88dfce278cebe2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/956fd6719622a0b02d6d643a596f61f93d1c7d2e625682cc0645716a828f2f449bb3e5800f87dbbc5abef350629faa1623ecf80aa4b49313d819a2c0b27c0193"} 11:32:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:32:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:32:02 INFO - Contents: 11:32:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46daf6c50c319a082331e94944d76c0ea274755fc257ff7e3bed4deec3fa4930311dc45704e2e0f0a6b82af1cdfa02b574a90299e0db5468a84e6a09415d63df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9532f833308f91ff74fc76ea7c7fa26b5a3b4ca62b6d5c0f5667c3ebaced33492f11be9328c17fbed7c7ddff3ac4c1d743ff2a4408f04a446e88dfce278cebe2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/956fd6719622a0b02d6d643a596f61f93d1c7d2e625682cc0645716a828f2f449bb3e5800f87dbbc5abef350629faa1623ecf80aa4b49313d819a2c0b27c0193"} 11:32:02 INFO - Running post-run listener: copy_logs_to_upload_dir 11:32:02 INFO - Copying logs to upload dir... 11:32:02 INFO - mkdir: /builds/slave/test/build/upload/logs 11:32:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.533818 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-10-05 11:32:02.253207) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 11:32:02.256684) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191 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/46daf6c50c319a082331e94944d76c0ea274755fc257ff7e3bed4deec3fa4930311dc45704e2e0f0a6b82af1cdfa02b574a90299e0db5468a84e6a09415d63df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9532f833308f91ff74fc76ea7c7fa26b5a3b4ca62b6d5c0f5667c3ebaced33492f11be9328c17fbed7c7ddff3ac4c1d743ff2a4408f04a446e88dfce278cebe2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/956fd6719622a0b02d6d643a596f61f93d1c7d2e625682cc0645716a828f2f449bb3e5800f87dbbc5abef350629faa1623ecf80aa4b49313d819a2c0b27c0193"} build_url:https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011352 build_url: 'https://queue.taskcluster.net/v1/task/HJ0qmajNSNWp9eCAHyA3Og/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/46daf6c50c319a082331e94944d76c0ea274755fc257ff7e3bed4deec3fa4930311dc45704e2e0f0a6b82af1cdfa02b574a90299e0db5468a84e6a09415d63df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9532f833308f91ff74fc76ea7c7fa26b5a3b4ca62b6d5c0f5667c3ebaced33492f11be9328c17fbed7c7ddff3ac4c1d743ff2a4408f04a446e88dfce278cebe2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/956fd6719622a0b02d6d643a596f61f93d1c7d2e625682cc0645716a828f2f449bb3e5800f87dbbc5abef350629faa1623ecf80aa4b49313d819a2c0b27c0193"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 11:32:02.282337) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:32:02.282694) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1475690645.545178-424363191 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004544 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:32:02.368691) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 11:32:02.369082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 11:32:02.369452) ========= ========= Total master_lag: 0.16 =========