builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1474650325.6 results: success (0) buildid: 20160923070600 builduid: d3a4345ffe5243efac999edc13dcd065 revision: bc1c04505a77efd394e1c0e633dd6ecd447ce522 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.597912) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.598856) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.599485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.615450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.616133) ========= 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=fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-23 10:05:25-- 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% 220M=0s 2016-09-23 10:05:26 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117017 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.748395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.748771) ========= 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=fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021383 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:25.802239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 10:05:25.803035) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bc1c04505a77efd394e1c0e633dd6ecd447ce522 --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 bc1c04505a77efd394e1c0e633dd6ecd447ce522 --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=fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-23 10:05:26,207 truncating revision to first 12 chars 2016-09-23 10:05:26,207 Setting DEBUG logging. 2016-09-23 10:05:26,207 attempt 1/10 2016-09-23 10:05:26,207 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bc1c04505a77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-23 10:05:27,375 unpacking tar archive at: mozilla-beta-bc1c04505a77/testing/mozharness/ program finished with exit code 0 elapsedTime=1.505682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 10:05:27.323902) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:27.324285) ========= script_repo_revision: bc1c04505a77efd394e1c0e633dd6ecd447ce522 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:27.324761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:27.325069) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 10:05:27.334694) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-23 10:05:27.335033) ========= /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=fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:05:27 INFO - MultiFileLogger online at 20160923 10:05:27 in /builds/slave/test 10:05:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:05:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:05:27 INFO - {'append_to_log': False, 10:05:27 INFO - 'base_work_dir': '/builds/slave/test', 10:05:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:05:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:05:27 INFO - 'branch': 'Mozilla-Beta', 10:05:27 INFO - 'buildbot_json_path': 'buildprops.json', 10:05:27 INFO - 'config_files': ('talos/linux_config.py',), 10:05:27 INFO - 'default_actions': ('clobber', 10:05:27 INFO - 'read-buildbot-config', 10:05:27 INFO - 'download-and-extract', 10:05:27 INFO - 'populate-webroot', 10:05:27 INFO - 'create-virtualenv', 10:05:27 INFO - 'install', 10:05:27 INFO - 'run-tests'), 10:05:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:05:27 INFO - 'download_minidump_stackwalk': True, 10:05:27 INFO - 'download_symbols': 'ondemand', 10:05:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:05:27 INFO - 'tooltool.py': '/tools/tooltool.py', 10:05:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:05:27 INFO - '/tools/misc-python/virtualenv.py')}, 10:05:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:05:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:05:27 INFO - 'installer_path': 'installer.exe', 10:05:27 INFO - 'log_level': 'info', 10:05:27 INFO - 'log_name': 'talos', 10:05:27 INFO - 'log_to_console': True, 10:05:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:05:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:05:27 INFO - 'opt_config_files': (), 10:05:27 INFO - 'pip_index': False, 10:05:27 INFO - 'sps_profile': False, 10:05:27 INFO - 'sps_profile_interval': 0, 10:05:27 INFO - 'suite': 'dromaeojs', 10:05:27 INFO - 'system_bits': '32', 10:05:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:05:27 INFO - 'title': 'talos-linux64-ix-048', 10:05:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:05:27 INFO - 'use_talos_json': True, 10:05:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:05:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:05:27 INFO - 'work_dir': 'build'} 10:05:27 INFO - [mozharness: 2016-09-23 17:05:27.826300Z] Running clobber step. 10:05:27 INFO - Running pre-action listener: _resource_record_pre_action 10:05:27 INFO - Running main action method: clobber 10:05:27 INFO - rmtree: /builds/slave/test/build 10:05:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:05:28 INFO - Running post-action listener: _resource_record_post_action 10:05:28 INFO - [mozharness: 2016-09-23 17:05:28.280165Z] Finished clobber step (success) 10:05:28 INFO - [mozharness: 2016-09-23 17:05:28.280267Z] Running read-buildbot-config step. 10:05:28 INFO - Running pre-action listener: _resource_record_pre_action 10:05:28 INFO - Running main action method: read_buildbot_config 10:05:28 INFO - Using buildbot properties: 10:05:28 INFO - { 10:05:28 INFO - "project": "", 10:05:28 INFO - "product": "firefox", 10:05:28 INFO - "script_repo_revision": "production", 10:05:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:05:28 INFO - "repository": "", 10:05:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:05:28 INFO - "buildid": "20160923070600", 10:05:28 INFO - "pgo_build": "True", 10:05:28 INFO - "basedir": "/builds/slave/test", 10:05:28 INFO - "buildnumber": 398, 10:05:28 INFO - "slavename": "talos-linux64-ix-048", 10:05:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:05:28 INFO - "platform": "ubuntu64_hw", 10:05:28 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:05:28 INFO - "branch": "mozilla-beta", 10:05:28 INFO - "repo_path": "releases/mozilla-beta", 10:05:28 INFO - "revision": "bc1c04505a77efd394e1c0e633dd6ecd447ce522", 10:05:28 INFO - "stage_platform": "linux64-pgo", 10:05:28 INFO - "builduid": "d3a4345ffe5243efac999edc13dcd065", 10:05:28 INFO - "slavebuilddir": "test" 10:05:28 INFO - } 10:05:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 10:05:28 INFO - Running post-action listener: _resource_record_post_action 10:05:28 INFO - [mozharness: 2016-09-23 17:05:28.282779Z] Finished read-buildbot-config step (success) 10:05:28 INFO - [mozharness: 2016-09-23 17:05:28.282869Z] Running download-and-extract step. 10:05:28 INFO - Running pre-action listener: _resource_record_pre_action 10:05:28 INFO - Running main action method: download_and_extract 10:05:28 INFO - mkdir: /builds/slave/test/build/tests 10:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:05:28 INFO - https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:05:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:05:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:05:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:05:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:05:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:05:28 INFO - retry: Failed, sleeping 30 seconds before retrying 10:05:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 10:05:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:05:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:05:58 INFO - retry: Failed, sleeping 60 seconds before retrying 10:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 10:06:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:06:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:06:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 10:06:58 INFO - Caught exception: HTTP Error 404: Not Found 10:06:58 INFO - Caught exception: HTTP Error 404: Not Found 10:06:58 INFO - Caught exception: HTTP Error 404: Not Found 10:06:58 INFO - trying https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:06:58 INFO - Downloading https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:06:58 INFO - Downloaded 1327 bytes. 10:06:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 10:06:58 INFO - Using the following test package requirements: 10:06:58 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:06:58 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:06:58 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 10:06:58 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'jsshell-linux-x86_64.zip'], 10:06:58 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:06:58 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 10:06:58 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 10:06:58 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 10:06:58 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:06:58 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 10:06:58 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:06:58 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:06:58 INFO - https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:06:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:06:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:06:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:06:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:06:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:06:58 INFO - retry: Failed, sleeping 30 seconds before retrying 10:07:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 10:07:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:07:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:07:29 INFO - retry: Failed, sleeping 60 seconds before retrying 10:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 10:08:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:08:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 10:08:29 INFO - Caught exception: HTTP Error 404: Not Found 10:08:29 INFO - Caught exception: HTTP Error 404: Not Found 10:08:29 INFO - Caught exception: HTTP Error 404: Not Found 10:08:29 INFO - trying https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:29 INFO - Downloading https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:08:30 INFO - Downloaded 21771304 bytes. 10:08:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:08:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:08:31 INFO - Return code: 0 10:08:31 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:08:31 INFO - https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:08:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:08:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:08:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:08:31 INFO - retry: Failed, sleeping 30 seconds before retrying 10:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 10:09:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:09:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:09:01 INFO - retry: Failed, sleeping 60 seconds before retrying 10:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 10:10:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:10:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:10:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 10:10:01 INFO - Caught exception: HTTP Error 404: Not Found 10:10:01 INFO - Caught exception: HTTP Error 404: Not Found 10:10:01 INFO - Caught exception: HTTP Error 404: Not Found 10:10:01 INFO - trying https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:10:01 INFO - Downloading https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 10:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:10:02 INFO - Downloaded 21771304 bytes. 10:10:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:10:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:10:04 INFO - Return code: 0 10:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:04 INFO - https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:10:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:10:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:10:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:10:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:10:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:10:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:10:04 INFO - retry: Failed, sleeping 30 seconds before retrying 10:10:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 10:10:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:10:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:10:34 INFO - retry: Failed, sleeping 60 seconds before retrying 10:11:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 10:11:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:11:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:11:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 10:11:34 INFO - Caught exception: HTTP Error 404: Not Found 10:11:34 INFO - Caught exception: HTTP Error 404: Not Found 10:11:34 INFO - Caught exception: HTTP Error 404: Not Found 10:11:34 INFO - trying https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:11:34 INFO - Downloading https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 10:11:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:11:34 INFO - Downloaded 14413740 bytes. 10:11:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:11:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 10:11:35 INFO - Return code: 0 10:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:11:35 INFO - https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:11:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:11:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:11:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:11:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:11:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:11:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:11:35 INFO - retry: Failed, sleeping 30 seconds before retrying 10:12:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 10:12:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:12:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:12:05 INFO - retry: Failed, sleeping 60 seconds before retrying 10:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 10:13:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:13:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:13:05 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 10:13:05 INFO - Caught exception: HTTP Error 404: Not Found 10:13:05 INFO - Caught exception: HTTP Error 404: Not Found 10:13:05 INFO - Caught exception: HTTP Error 404: Not Found 10:13:05 INFO - trying https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:13:05 INFO - Downloading https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:13:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:13:06 INFO - Downloaded 56799560 bytes. 10:13:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:13:06 INFO - mkdir: /builds/slave/test/properties 10:13:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:13:06 INFO - Writing to file /builds/slave/test/properties/build_url 10:13:06 INFO - Contents: 10:13:06 INFO - build_url:https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 10:13:07 INFO - Running post-action listener: _resource_record_post_action 10:13:07 INFO - Running post-action listener: set_extra_try_arguments 10:13:07 INFO - [mozharness: 2016-09-23 17:13:07.077825Z] Finished download-and-extract step (success) 10:13:07 INFO - [mozharness: 2016-09-23 17:13:07.078062Z] Running populate-webroot step. 10:13:07 INFO - Running pre-action listener: _resource_record_pre_action 10:13:07 INFO - Running main action method: populate_webroot 10:13:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:13:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:13:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:13:07 INFO - '--tppagecycles', 10:13:07 INFO - '7'], 10:13:07 INFO - 'tests': ['tsvgm']}}, 10:13:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:13:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:13:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:13:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:13:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:13:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['damp', 'tps']}, 10:13:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'tests': ['damp', 'tps']}, 10:13:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['dromaeo_dom']}, 10:13:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:13:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['basic_compositor_video']}, 10:13:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:13:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['a11yr', 10:13:07 INFO - 'ts_paint', 10:13:07 INFO - 'tpaint', 10:13:07 INFO - 'sessionrestore', 10:13:07 INFO - 'sessionrestore_no_auto_restore', 10:13:07 INFO - 'tabpaint']}, 10:13:07 INFO - 'other-e10s': {'tests': ['a11yr', 10:13:07 INFO - 'ts_paint', 10:13:07 INFO - 'tpaint', 10:13:07 INFO - 'sessionrestore', 10:13:07 INFO - 'sessionrestore_no_auto_restore', 10:13:07 INFO - 'tabpaint']}, 10:13:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:13:07 INFO - 'ts_paint', 10:13:07 INFO - 'tpaint', 10:13:07 INFO - 'sessionrestore', 10:13:07 INFO - 'sessionrestore_no_auto_restore', 10:13:07 INFO - 'tabpaint']}, 10:13:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:13:07 INFO - 'ts_paint', 10:13:07 INFO - 'tpaint', 10:13:07 INFO - 'sessionrestore', 10:13:07 INFO - 'sessionrestore_no_auto_restore', 10:13:07 INFO - 'tabpaint']}, 10:13:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['a11yr', 10:13:07 INFO - 'ts_paint', 10:13:07 INFO - 'tpaint', 10:13:07 INFO - 'sessionrestore', 10:13:07 INFO - 'sessionrestore_no_auto_restore', 10:13:07 INFO - 'tabpaint']}, 10:13:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['a11yr', 10:13:07 INFO - 'ts_paint', 10:13:07 INFO - 'tpaint', 10:13:07 INFO - 'sessionrestore', 10:13:07 INFO - 'sessionrestore_no_auto_restore', 10:13:07 INFO - 'tabpaint']}, 10:13:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['tsvgx', 10:13:07 INFO - 'tsvgr_opacity', 10:13:07 INFO - 'tart', 10:13:07 INFO - 'tscrollx', 10:13:07 INFO - 'cart']}, 10:13:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:13:07 INFO - 'tsvgr_opacity', 10:13:07 INFO - 'tart', 10:13:07 INFO - 'tscrollx', 10:13:07 INFO - 'cart']}, 10:13:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'talos_options': ['--disable-e10s'], 10:13:07 INFO - 'tests': ['tp5o']}, 10:13:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'tests': ['tp5o']}, 10:13:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'talos_options': ['--disable-e10s', 10:13:07 INFO - '--xperf_path', 10:13:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:07 INFO - 'tests': ['tp5n']}, 10:13:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:13:07 INFO - 'talos_options': ['--xperf_path', 10:13:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:13:07 INFO - 'tests': ['tp5n']}}, 10:13:07 INFO - 'talos.zip': {'path': '', 10:13:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:13:07 INFO - Running post-action listener: _resource_record_post_action 10:13:07 INFO - [mozharness: 2016-09-23 17:13:07.096211Z] Finished populate-webroot step (success) 10:13:07 INFO - [mozharness: 2016-09-23 17:13:07.096301Z] Running create-virtualenv step. 10:13:07 INFO - Running pre-action listener: _resource_record_pre_action 10:13:07 INFO - Running main action method: create_virtualenv 10:13:07 INFO - Creating virtualenv /builds/slave/test/build/venv 10:13:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:13:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:13:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:13:07 INFO - Using real prefix '/usr' 10:13:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:13:07 INFO - Installing distribute.............................................................................................................................................................................................done. 10:13:09 INFO - Installing pip.................done. 10:13:09 INFO - Return code: 0 10:13:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:09 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:13:09 INFO - Using env: {'DISPLAY': ':0', 10:13:09 INFO - 'HOME': '/home/cltbld', 10:13:09 INFO - 'LANG': 'en_US.UTF-8', 10:13:09 INFO - 'LANGUAGE': 'en_US:en', 10:13:09 INFO - 'LOGNAME': 'cltbld', 10:13:09 INFO - 'MAIL': '/var/mail/cltbld', 10:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:09 INFO - 'MOZ_NO_REMOTE': '1', 10:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:09 INFO - 'NO_EM_RESTART': '1', 10:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:09 INFO - 'PWD': '/builds/slave/test', 10:13:09 INFO - 'SHELL': '/bin/bash', 10:13:09 INFO - 'SHLVL': '1', 10:13:09 INFO - 'TERM': 'linux', 10:13:09 INFO - 'TMOUT': '86400', 10:13:09 INFO - 'USER': 'cltbld', 10:13:09 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:09 INFO - '_': '/tools/buildbot/bin/python'} 10:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:13:09 INFO - Cleaning up... 10:13:09 INFO - Return code: 0 10:13:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:09 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:13:09 INFO - Using env: {'DISPLAY': ':0', 10:13:09 INFO - 'HOME': '/home/cltbld', 10:13:09 INFO - 'LANG': 'en_US.UTF-8', 10:13:09 INFO - 'LANGUAGE': 'en_US:en', 10:13:09 INFO - 'LOGNAME': 'cltbld', 10:13:09 INFO - 'MAIL': '/var/mail/cltbld', 10:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:09 INFO - 'MOZ_NO_REMOTE': '1', 10:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:09 INFO - 'NO_EM_RESTART': '1', 10:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:09 INFO - 'PWD': '/builds/slave/test', 10:13:09 INFO - 'SHELL': '/bin/bash', 10:13:09 INFO - 'SHLVL': '1', 10:13:09 INFO - 'TERM': 'linux', 10:13:09 INFO - 'TMOUT': '86400', 10:13:09 INFO - 'USER': 'cltbld', 10:13:09 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:09 INFO - '_': '/tools/buildbot/bin/python'} 10:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:10 INFO - Downloading/unpacking psutil>=3.1.1 10:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:13:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:12 INFO - Installing collected packages: psutil 10:13:12 INFO - Running setup.py install for psutil 10:13:12 INFO - building 'psutil._psutil_linux' extension 10:13:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:13:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:13:12 INFO - building 'psutil._psutil_posix' extension 10:13:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:13:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:13:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:13:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:13:12 INFO - Successfully installed psutil 10:13:12 INFO - Cleaning up... 10:13:12 INFO - Return code: 0 10:13:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:12 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:13:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:13:12 INFO - Using env: {'DISPLAY': ':0', 10:13:12 INFO - 'HOME': '/home/cltbld', 10:13:12 INFO - 'LANG': 'en_US.UTF-8', 10:13:12 INFO - 'LANGUAGE': 'en_US:en', 10:13:12 INFO - 'LOGNAME': 'cltbld', 10:13:12 INFO - 'MAIL': '/var/mail/cltbld', 10:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:12 INFO - 'MOZ_NO_REMOTE': '1', 10:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:12 INFO - 'NO_EM_RESTART': '1', 10:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:12 INFO - 'PWD': '/builds/slave/test', 10:13:12 INFO - 'SHELL': '/bin/bash', 10:13:12 INFO - 'SHLVL': '1', 10:13:12 INFO - 'TERM': 'linux', 10:13:12 INFO - 'TMOUT': '86400', 10:13:12 INFO - 'USER': 'cltbld', 10:13:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:12 INFO - '_': '/tools/buildbot/bin/python'} 10:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:13:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:13:14 INFO - Installing collected packages: mozsystemmonitor 10:13:14 INFO - Running setup.py install for mozsystemmonitor 10:13:14 INFO - Successfully installed mozsystemmonitor 10:13:14 INFO - Cleaning up... 10:13:14 INFO - Return code: 0 10:13:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:14 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:13:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:13:14 INFO - Using env: {'DISPLAY': ':0', 10:13:14 INFO - 'HOME': '/home/cltbld', 10:13:14 INFO - 'LANG': 'en_US.UTF-8', 10:13:14 INFO - 'LANGUAGE': 'en_US:en', 10:13:14 INFO - 'LOGNAME': 'cltbld', 10:13:14 INFO - 'MAIL': '/var/mail/cltbld', 10:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:14 INFO - 'MOZ_NO_REMOTE': '1', 10:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:14 INFO - 'NO_EM_RESTART': '1', 10:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:14 INFO - 'PWD': '/builds/slave/test', 10:13:14 INFO - 'SHELL': '/bin/bash', 10:13:14 INFO - 'SHLVL': '1', 10:13:14 INFO - 'TERM': 'linux', 10:13:14 INFO - 'TMOUT': '86400', 10:13:14 INFO - 'USER': 'cltbld', 10:13:14 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:14 INFO - '_': '/tools/buildbot/bin/python'} 10:13:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:15 INFO - Downloading/unpacking jsonschema==2.5.1 10:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:13:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:17 INFO - Downloading functools32-3.2.3-2.tar.gz 10:13:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:13:17 INFO - warning: no files found matching '*.txt' 10:13:17 INFO - no previously-included directories found matching 'build' 10:13:17 INFO - no previously-included directories found matching 'dist' 10:13:17 INFO - no previously-included directories found matching '.git*' 10:13:17 INFO - Installing collected packages: jsonschema, functools32 10:13:17 INFO - Running setup.py install for functools32 10:13:17 INFO - warning: no files found matching '*.txt' 10:13:17 INFO - no previously-included directories found matching 'build' 10:13:17 INFO - no previously-included directories found matching 'dist' 10:13:17 INFO - no previously-included directories found matching '.git*' 10:13:17 INFO - Successfully installed jsonschema functools32 10:13:17 INFO - Cleaning up... 10:13:17 INFO - Return code: 0 10:13:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:17 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:13:17 INFO - Using env: {'DISPLAY': ':0', 10:13:17 INFO - 'HOME': '/home/cltbld', 10:13:17 INFO - 'LANG': 'en_US.UTF-8', 10:13:17 INFO - 'LANGUAGE': 'en_US:en', 10:13:17 INFO - 'LOGNAME': 'cltbld', 10:13:17 INFO - 'MAIL': '/var/mail/cltbld', 10:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:17 INFO - 'MOZ_NO_REMOTE': '1', 10:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:17 INFO - 'NO_EM_RESTART': '1', 10:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:17 INFO - 'PWD': '/builds/slave/test', 10:13:17 INFO - 'SHELL': '/bin/bash', 10:13:17 INFO - 'SHLVL': '1', 10:13:17 INFO - 'TERM': 'linux', 10:13:17 INFO - 'TMOUT': '86400', 10:13:17 INFO - 'USER': 'cltbld', 10:13:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:17 INFO - '_': '/tools/buildbot/bin/python'} 10:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:13:17 INFO - Cleaning up... 10:13:17 INFO - Return code: 0 10:13:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:17 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:13:17 INFO - Using env: {'DISPLAY': ':0', 10:13:17 INFO - 'HOME': '/home/cltbld', 10:13:17 INFO - 'LANG': 'en_US.UTF-8', 10:13:17 INFO - 'LANGUAGE': 'en_US:en', 10:13:17 INFO - 'LOGNAME': 'cltbld', 10:13:17 INFO - 'MAIL': '/var/mail/cltbld', 10:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:17 INFO - 'MOZ_NO_REMOTE': '1', 10:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:17 INFO - 'NO_EM_RESTART': '1', 10:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:17 INFO - 'PWD': '/builds/slave/test', 10:13:17 INFO - 'SHELL': '/bin/bash', 10:13:17 INFO - 'SHLVL': '1', 10:13:17 INFO - 'TERM': 'linux', 10:13:17 INFO - 'TMOUT': '86400', 10:13:17 INFO - 'USER': 'cltbld', 10:13:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:17 INFO - '_': '/tools/buildbot/bin/python'} 10:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:17 INFO - Downloading/unpacking blobuploader==1.2.4 10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:20 INFO - Downloading blobuploader-1.2.4.tar.gz 10:13:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:13:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:13:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:21 INFO - Downloading docopt-0.6.1.tar.gz 10:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:13:21 INFO - Installing collected packages: blobuploader, requests, docopt 10:13:21 INFO - Running setup.py install for blobuploader 10:13:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:13:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:13:21 INFO - Running setup.py install for requests 10:13:22 INFO - Running setup.py install for docopt 10:13:22 INFO - Successfully installed blobuploader requests docopt 10:13:22 INFO - Cleaning up... 10:13:22 INFO - Return code: 0 10:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13: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')]} 10:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13: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')]} 10:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:22 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:13:22 INFO - Using env: {'DISPLAY': ':0', 10:13:22 INFO - 'HOME': '/home/cltbld', 10:13:22 INFO - 'LANG': 'en_US.UTF-8', 10:13:22 INFO - 'LANGUAGE': 'en_US:en', 10:13:22 INFO - 'LOGNAME': 'cltbld', 10:13:22 INFO - 'MAIL': '/var/mail/cltbld', 10:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:22 INFO - 'MOZ_NO_REMOTE': '1', 10:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:22 INFO - 'NO_EM_RESTART': '1', 10:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:22 INFO - 'PWD': '/builds/slave/test', 10:13:22 INFO - 'SHELL': '/bin/bash', 10:13:22 INFO - 'SHLVL': '1', 10:13:22 INFO - 'TERM': 'linux', 10:13:22 INFO - 'TMOUT': '86400', 10:13:22 INFO - 'USER': 'cltbld', 10:13:22 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:22 INFO - '_': '/tools/buildbot/bin/python'} 10:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:22 INFO - Running setup.py (path:/tmp/pip-xZbO9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:22 INFO - Running setup.py (path:/tmp/pip-wEMZCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:22 INFO - Running setup.py (path:/tmp/pip-Hxnl40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:22 INFO - Running setup.py (path:/tmp/pip-qEbZQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:22 INFO - Running setup.py (path:/tmp/pip-9cKOuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:22 INFO - Running setup.py (path:/tmp/pip-7jVVD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:22 INFO - Running setup.py (path:/tmp/pip-UNtn8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:22 INFO - Running setup.py (path:/tmp/pip-L4bstf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:23 INFO - Running setup.py (path:/tmp/pip-emfCRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:23 INFO - Running setup.py (path:/tmp/pip-_e_oJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:23 INFO - Running setup.py (path:/tmp/pip-IX4yJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:23 INFO - Running setup.py (path:/tmp/pip-PXUFkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:23 INFO - Running setup.py (path:/tmp/pip-rmEA4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:23 INFO - Running setup.py (path:/tmp/pip-p1YI8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:23 INFO - Running setup.py (path:/tmp/pip-eH0NLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:23 INFO - Running setup.py (path:/tmp/pip-5QwoIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:23 INFO - Running setup.py (path:/tmp/pip-E7rvLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:13:23 INFO - Running setup.py install for manifestparser 10:13:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:13:23 INFO - Running setup.py install for mozcrash 10:13:23 INFO - Running setup.py install for mozdebug 10:13:23 INFO - Running setup.py install for mozdevice 10:13:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Running setup.py install for mozfile 10:13:24 INFO - Running setup.py install for mozhttpd 10:13:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Running setup.py install for mozinfo 10:13:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Running setup.py install for mozInstall 10:13:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Running setup.py install for mozleak 10:13:24 INFO - Running setup.py install for mozlog 10:13:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Running setup.py install for moznetwork 10:13:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:13:24 INFO - Running setup.py install for mozprocess 10:13:24 INFO - Running setup.py install for mozprofile 10:13:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:13:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:13:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:13:25 INFO - Running setup.py install for mozrunner 10:13:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:13:25 INFO - Running setup.py install for mozscreenshot 10:13:25 INFO - Running setup.py install for moztest 10:13:25 INFO - Running setup.py install for mozversion 10:13:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:13:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:13:25 INFO - Cleaning up... 10:13:25 INFO - Return code: 0 10:13:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:25 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:13:25 INFO - Using env: {'DISPLAY': ':0', 10:13:25 INFO - 'HOME': '/home/cltbld', 10:13:25 INFO - 'LANG': 'en_US.UTF-8', 10:13:25 INFO - 'LANGUAGE': 'en_US:en', 10:13:25 INFO - 'LOGNAME': 'cltbld', 10:13:25 INFO - 'MAIL': '/var/mail/cltbld', 10:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:25 INFO - 'MOZ_NO_REMOTE': '1', 10:13:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:25 INFO - 'NO_EM_RESTART': '1', 10:13:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:25 INFO - 'PWD': '/builds/slave/test', 10:13:25 INFO - 'SHELL': '/bin/bash', 10:13:25 INFO - 'SHLVL': '1', 10:13:25 INFO - 'TERM': 'linux', 10:13:25 INFO - 'TMOUT': '86400', 10:13:25 INFO - 'USER': 'cltbld', 10:13:25 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:25 INFO - '_': '/tools/buildbot/bin/python'} 10:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:13:25 INFO - Running setup.py (path:/tmp/pip-_njgQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:13:25 INFO - Running setup.py (path:/tmp/pip-WRuDkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:13:25 INFO - Running setup.py (path:/tmp/pip-RbWuSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:13:25 INFO - Running setup.py (path:/tmp/pip-MCITwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:13:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:13:25 INFO - Running setup.py (path:/tmp/pip-5ZX2TT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:13:26 INFO - Running setup.py (path:/tmp/pip-p4kpDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:13:26 INFO - Running setup.py (path:/tmp/pip-AbBjWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:13:26 INFO - Running setup.py (path:/tmp/pip-XdVSNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:13:26 INFO - Running setup.py (path:/tmp/pip-VW82jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:13:26 INFO - Running setup.py (path:/tmp/pip-mZjNAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:13:26 INFO - Running setup.py (path:/tmp/pip-6eQ4lU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:13:26 INFO - Running setup.py (path:/tmp/pip-KXisBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:13:26 INFO - Running setup.py (path:/tmp/pip-GQXH9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:13:26 INFO - Running setup.py (path:/tmp/pip-uuncw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:26 INFO - Running setup.py (path:/tmp/pip-je1bmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:13:26 INFO - Running setup.py (path:/tmp/pip-uprbMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:13:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:13:26 INFO - Running setup.py (path:/tmp/pip-xZpMgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:13:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:13:28 INFO - Downloading blessings-1.6.tar.gz 10:13:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:13:28 INFO - Installing collected packages: blessings 10:13:28 INFO - Running setup.py install for blessings 10:13:28 INFO - Successfully installed blessings 10:13:28 INFO - Cleaning up... 10:13:29 INFO - Return code: 0 10:13:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:13:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:13:29 INFO - Reading from file tmpfile_stdout 10:13:29 INFO - Current package versions: 10:13:29 INFO - argparse == 1.2.1 10:13:29 INFO - blessings == 1.6 10:13:29 INFO - blobuploader == 1.2.4 10:13:29 INFO - docopt == 0.6.1 10:13:29 INFO - functools32 == 3.2.3-2 10:13:29 INFO - jsonschema == 2.5.1 10:13:29 INFO - manifestparser == 1.1 10:13:29 INFO - mozInstall == 1.12 10:13:29 INFO - mozcrash == 0.17 10:13:29 INFO - mozdebug == 0.1 10:13:29 INFO - mozdevice == 0.48 10:13:29 INFO - mozfile == 1.2 10:13:29 INFO - mozhttpd == 0.7 10:13:29 INFO - mozinfo == 0.9 10:13:29 INFO - mozleak == 0.1 10:13:29 INFO - mozlog == 3.3 10:13:29 INFO - moznetwork == 0.27 10:13:29 INFO - mozprocess == 0.23 10:13:29 INFO - mozprofile == 0.28 10:13:29 INFO - mozrunner == 6.12 10:13:29 INFO - mozscreenshot == 0.1 10:13:29 INFO - mozsystemmonitor == 0.3 10:13:29 INFO - moztest == 0.7 10:13:29 INFO - mozversion == 1.4 10:13:29 INFO - psutil == 3.1.1 10:13:29 INFO - requests == 1.2.3 10:13:29 INFO - wsgiref == 0.1.2 10:13:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:29 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:13:29 INFO - Using env: {'DISPLAY': ':0', 10:13:29 INFO - 'HOME': '/home/cltbld', 10:13:29 INFO - 'LANG': 'en_US.UTF-8', 10:13:29 INFO - 'LANGUAGE': 'en_US:en', 10:13:29 INFO - 'LOGNAME': 'cltbld', 10:13:29 INFO - 'MAIL': '/var/mail/cltbld', 10:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:29 INFO - 'MOZ_NO_REMOTE': '1', 10:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:29 INFO - 'NO_EM_RESTART': '1', 10:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:29 INFO - 'PWD': '/builds/slave/test', 10:13:29 INFO - 'SHELL': '/bin/bash', 10:13:29 INFO - 'SHLVL': '1', 10:13:29 INFO - 'TERM': 'linux', 10:13:29 INFO - 'TMOUT': '86400', 10:13:29 INFO - 'USER': 'cltbld', 10:13:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:29 INFO - '_': '/tools/buildbot/bin/python'} 10:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:13:29 INFO - Cleaning up... 10:13:29 INFO - Return code: 0 10:13:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:13:29 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 0x29d2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b358a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, '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': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:13:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:13:29 INFO - Using env: {'DISPLAY': ':0', 10:13:29 INFO - 'HOME': '/home/cltbld', 10:13:29 INFO - 'LANG': 'en_US.UTF-8', 10:13:29 INFO - 'LANGUAGE': 'en_US:en', 10:13:29 INFO - 'LOGNAME': 'cltbld', 10:13:29 INFO - 'MAIL': '/var/mail/cltbld', 10:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:29 INFO - 'MOZ_NO_REMOTE': '1', 10:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:29 INFO - 'NO_EM_RESTART': '1', 10:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:29 INFO - 'PWD': '/builds/slave/test', 10:13:29 INFO - 'SHELL': '/bin/bash', 10:13:29 INFO - 'SHLVL': '1', 10:13:29 INFO - 'TERM': 'linux', 10:13:29 INFO - 'TMOUT': '86400', 10:13:29 INFO - 'USER': 'cltbld', 10:13:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:29 INFO - '_': '/tools/buildbot/bin/python'} 10:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:13:29 INFO - Cleaning up... 10:13:29 INFO - Return code: 0 10:13:29 INFO - Running post-action listener: _resource_record_post_action 10:13:29 INFO - Running post-action listener: _start_resource_monitoring 10:13:29 INFO - Starting resource monitoring. 10:13:29 INFO - [mozharness: 2016-09-23 17:13:29.748138Z] Finished create-virtualenv step (success) 10:13:29 INFO - [mozharness: 2016-09-23 17:13:29.748570Z] Running install step. 10:13:29 INFO - Running pre-action listener: _resource_record_pre_action 10:13:29 INFO - Running main action method: install 10:13:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:13:29 INFO - Reading from file tmpfile_stdout 10:13:29 INFO - Detecting whether we're running mozinstall >=1.0... 10:13:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:13:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:13:30 INFO - Reading from file tmpfile_stdout 10:13:30 INFO - Output received: 10:13:30 INFO - Usage: mozinstall [options] installer 10:13:30 INFO - Options: 10:13:30 INFO - -h, --help show this help message and exit 10:13:30 INFO - -d DEST, --destination=DEST 10:13:30 INFO - Directory to install application into. [default: 10:13:30 INFO - "/builds/slave/test"] 10:13:30 INFO - --app=APP Application being installed. [default: firefox] 10:13:30 INFO - mkdir: /builds/slave/test/build/application 10:13:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:13:43 INFO - Reading from file tmpfile_stdout 10:13:43 INFO - Output received: 10:13:43 INFO - /builds/slave/test/build/application/firefox/firefox 10:13:43 INFO - Running post-action listener: _resource_record_post_action 10:13:43 INFO - [mozharness: 2016-09-23 17:13:43.528078Z] Finished install step (success) 10:13:43 INFO - [mozharness: 2016-09-23 17:13:43.528360Z] Running run-tests step. 10:13:43 INFO - Running pre-action listener: _resource_record_pre_action 10:13:43 INFO - Running main action method: run_tests 10:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:13:43 INFO - Python 2.7.3 10:13:43 INFO - Return code: 0 10:13:43 INFO - grabbing minidump binary from tooltool 10:13: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')]} 10:13:43 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 0x2c913b0>, '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 0x2c922b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c92740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:13:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:13:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:13:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:13:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:13:43 INFO - Return code: 0 10:13:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:13:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:13:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:13:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:13:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:13:43 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/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:13:43 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/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:13:43 INFO - Using env: {'DISPLAY': ':0', 10:13:43 INFO - 'HOME': '/home/cltbld', 10:13:43 INFO - 'LANG': 'en_US.UTF-8', 10:13:43 INFO - 'LANGUAGE': 'en_US:en', 10:13:43 INFO - 'LOGNAME': 'cltbld', 10:13:43 INFO - 'MAIL': '/var/mail/cltbld', 10:13:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:13:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:43 INFO - 'MOZ_NO_REMOTE': '1', 10:13:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:13:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:13:43 INFO - 'NO_EM_RESTART': '1', 10:13:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:43 INFO - 'PWD': '/builds/slave/test', 10:13:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:13:43 INFO - 'SHELL': '/bin/bash', 10:13:43 INFO - 'SHLVL': '1', 10:13:43 INFO - 'TERM': 'linux', 10:13:43 INFO - 'TMOUT': '86400', 10:13:43 INFO - 'USER': 'cltbld', 10:13:43 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017', 10:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:43 INFO - '_': '/tools/buildbot/bin/python'} 10:13:43 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/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:13:43 INFO - mozversion application_buildid: 20160923070600 10:13:43 INFO - mozversion application_changeset: bc1c04505a77efd394e1c0e633dd6ecd447ce522 10:13:43 INFO - mozversion application_display_name: Firefox 10:13:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:13:43 INFO - mozversion application_name: Firefox 10:13:43 INFO - mozversion application_remotingname: firefox 10:13:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:13:43 INFO - mozversion application_vendor: Mozilla 10:13:43 INFO - mozversion application_version: 50.0 10:13:43 INFO - mozversion platform_buildid: 20160923070600 10:13:43 INFO - mozversion platform_changeset: bc1c04505a77efd394e1c0e633dd6ecd447ce522 10:13:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:13:43 INFO - mozversion platform_version: 50.0 10:13:43 INFO - using testdate: 1474650823 10:13:43 INFO - actual date: 1474650823 10:13:43 INFO - starting webserver on 'localhost:35655' 10:13:43 INFO - SUITE-START | Running 2 tests 10:13:43 INFO - TEST-START | dromaeo_css 10:13:43 INFO - operating with platform_type : linux_ 10:13:43 INFO - Initialising browser for dromaeo_css test... 10:13:43 INFO - TEST-INFO | started process 6727 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFfdnlT/profile http://localhost:35655/getInfo.html) 10:13:48 INFO - PROCESS | 6727 | __metrics Screen width/height:1600/1200 10:13:48 INFO - PROCESS | 6727 | colorDepth:24 10:13:48 INFO - PROCESS | 6727 | Browser inner width/height: 1024/697 10:13:48 INFO - PROCESS | 6727 | __metrics 10:13:49 INFO - PROCESS | 6727 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:13:49 INFO - PROCESS | 6727 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:13:50 INFO - TEST-INFO | 6727: exit 0 10:13:50 INFO - Browser initialized. 10:13:50 INFO - Running cycle 1/1 for dromaeo_css test... 10:13:50 INFO - TEST-INFO | started process 6814 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFfdnlT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:01 INFO - PROCESS | 6814 | RSS: Main: 143716352 10:14:01 INFO - PROCESS | 6814 | 10:14:53 INFO - PROCESS | 6814 | 1474650893098 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:17:01 INFO - PROCESS | 6814 | Cycle 1(1): loaded http://localhost:35655/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35655/tests/dromaeo/cssquery-ext.html) 10:17:02 INFO - PROCESS | 6814 | RSS: Main: 217403392 10:17:02 INFO - PROCESS | 6814 | 10:20:04 INFO - PROCESS | 6814 | Cycle 1(1): loaded http://localhost:35655/tests/dromaeo/cssquery-ext.html (next: http://localhost:35655/tests/dromaeo/cssquery-jquery.html) 10:20:05 INFO - PROCESS | 6814 | RSS: Main: 235712512 10:20:05 INFO - PROCESS | 6814 | 10:23:06 INFO - PROCESS | 6814 | Cycle 1(1): loaded http://localhost:35655/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35655/tests/dromaeo/cssquery-mootools.html) 10:23:07 INFO - PROCESS | 6814 | RSS: Main: 174600192 10:23:07 INFO - PROCESS | 6814 | 10:26:09 INFO - PROCESS | 6814 | Cycle 1(1): loaded http://localhost:35655/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35655/tests/dromaeo/cssquery-prototype.html) 10:26:10 INFO - PROCESS | 6814 | RSS: Main: 199757824 10:26:10 INFO - PROCESS | 6814 | 10:29:12 INFO - PROCESS | 6814 | Cycle 1(1): loaded http://localhost:35655/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35655/tests/dromaeo/cssquery-yui.html) 10:29:13 INFO - PROCESS | 6814 | RSS: Main: 175255552 10:29:13 INFO - PROCESS | 6814 | 10:32:15 INFO - PROCESS | 6814 | Cycle 1(1): loaded http://localhost:35655/tests/dromaeo/cssquery-yui.html (next: http://localhost:35655/tests/dromaeo/cssquery-dojo.html) 10:32:16 INFO - PROCESS | 6814 | RSS: Main: 261292032 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | __start_tp_report 10:32:16 INFO - PROCESS | 6814 | _x_x_mozilla_page_load 10:32:16 INFO - PROCESS | 6814 | _x_x_mozilla_page_load_details 10:32:16 INFO - PROCESS | 6814 | |i|pagename|runs| 10:32:16 INFO - PROCESS | 6814 | |0;dojo.html;3173.1908363367343;3208.791428557144;3223.5487031815537;3252.4470839957203;3264.2165880188763;5517.917231241529;5600.243967064445;5619.690916999569;5628.9999999999945;5636.943630563693;5609.074502707059;5644.068728659777;5645.5483561315095;5645.604807663465;5686.829395118143;5032.069067222557;5050.898982020358;5099.5665368443815;5114.846554603368;5171.4311425743135;5041.596672266228;5089.592832573385;5106.489351064901;5131.7690703918415;5176.5341119299255;5126.051680439112;5195.9480405195845;5200.427952925175;5202.245674377213;5204.193350030751;5137.845864624067;5191.584673226152;5191.8442446726285;5196.272521846944;5231.869203269911;4919.754012299407;4956.628252881048;5074.238864170404;5092.949070509321;5102.158143906251;5526.336839579236;5529.972350138223;5543.196236545718;5543.805966791183;5579.525740312079;5917.585768996172;5960.374160713106;5974.551908606828;5974.850628734316;5979.790707325266;5831.825045248649;5897.764089436418;5904.409559044104;5941.643501389931;6049.606775559574;5623.662580245198;5628.183913332544;5647.943520564783;5656.179853921159;5656.971715141439;6134.04922237054;6139.57023008394;6141.723622437001;6144.8771024580155;6151.661658608819;5696.544276457874;5819.941800582024;5864.706764661835;5878.706064696748;5977.043673012384;6011;6015.488683461955;6017.879642407215;6025.427584379477;6027.9095813562835;5981.5214782817275;5991.610545314541;6001.3098493672915;6002.489788368038;6006.969965150146;5710.942890571041;5861;5895.764169433261;5897.410258974069;5961;4586.449626044896;4586.931196032062;4587.3807036050375;4589.380433641416;4613.907721845544;4089.284375234322;4094.488188976378;4099.057216840143;4101.1387608601935;4130.173965206911;5487.6158668892795;5639.182318563785;5656.066748986371;5676.233708449389;5751.4536119068625;6037.516998640098;6046.637201767908;6047.03247480401;6050.30421501533;6052.213212282375;5715.342735585377;5716.056850619601;5741.110127930177;5746.683932383759;5746.712664366765;4971.502849715;5018.397792264878;5044.1172794761505;5054.064997975386;5126.53861152498;6228.128062071223;6231.127642130105;6237.625742455467;6238.875222495525;6242.968785156045;6005.159277701213;6037.335893051673;6057.454829065229;6058.878822423615;6068.150458935665;6014;6075.908861366995;6116.487945662109;6142.78500252489;6183.196184495987;5913.615614985012;5919.112133179885;5921.171036055042;5928.9407105928385;5957.582969192288;6011.639301641915;6028.758849645965;6030.03519436888;6035.547333949883;6037.547183961308;5650.491455769001;5738.397468265772;5781.4218578141845;5809.593328466966;5876.823695289148;5819.127130930395;5823.970880145572;5826.213461182686;5830.416958304136;5830.4461076197695;5739.8852022960145;5740.1963725077685;5778.27771528559;5788.7395067222915;5796.768129274782;5705;5820.068788993741;5896.7936122235515;5897;5959.165716799738;5804.21643078179;5804.709764511673;5808.970955145197;5810.564207684356;5842.532597392284 10:32:16 INFO - PROCESS | 6814 | |1;ext.html;606.7845914700281;639.1499305923122;658.4732214228617;666.2105405094962;671.1811589860368;34961;37901.431478527775;37925.810370948144;37936.810315948416;46060;44380.3342949856;48787.26819097716;48821;48894.26658600115;48923.510764892344;9186.356955013152;9346.859797103049;9348.766280842965;9359.344845860793;9368.250539956787;8628.525431101287;8833.95583022084;9195.908040919605;9301.209397201246;9304.81390372192;11039.337639741601;11244.100471962265;11325.207235493519;11771.293722376642;11779.587714429998;11246.66260012201;11273.943630281878;11284.266522676082;11313.264637798517;11323.830142547868;11117.944410277896;11177.161712871568;11194.496247668874;11199.160062995226;11200.719982000433;46145.077098458176;46334.53665463336;46364.53635463636;46383.76808115972;46387.30419043717;3892.805359732002;3925.411188321746;4022.5776293489057;4064.959350406488;4319.07139964909;36770.448443273104;36780.81609591935;36906.63093369059;39161.21677566461;40083.59916400857;4559.338895860082;4632.490426053131;4740.881477963044;4760.64295177863;4847.297141914438;394810;401313;401563;401682.9999999971;402996;40062;41092.38361424581;68838.65580672065;69968.30031699719;70020;41646.37530437046;42056.36915446271;42109.157816844105;42119;42159;17928.282868685106;17991.640167196845;18094.909525452553;18104.36634717805;18106.45680629583;15290.923545382202;15418.46035388756;15421.151836648867;15426.6143346415;15434.691306173814;9037.819243615091;9335.439873607605;9527.38072025316;9576;9645.9035409645;3662.304162209172;3685.4103343464917;3719.442083687414;3720.795356255432;3725.832337544817;1056.508723443587;1056.529844219315;1071.908887744535;1135.5401062569672;1171.8769529199483;34778.652213477544;35114.47328290078;35308.64691353105;35422.34332425027;35565.46651800225;1160.4313886195703;1160.9941950290363;1206.4329765010432;1213.5570516761318;1250.5123002029216;17714.645707085787;18732.90633546851;19307.324243651685;19723;20130.19479220844;622297;626969;627822;929870;930856;67049.32950670528;67235.32764672389;67691;67737.66131169313;67767;1507.0806807847205;1515.113658509784;1516.5677781832126;1535.1173075481643;1547.597624617585;35273.47089793604;36677;36692.63307366892;37049.629503704615;37112.628873712;57649.423505766084;58441.4155858436;58441.70779146077;58464.707676461345;58481.415185847596;542912;548311;548717;549162;551377;36162.03370876306;42098.57901420946;45072;49770.258040968394;50327.24509132439;50087.248691268935;51327;51474.48525514697;51559.48440515547;52407.47592524026;44077.11845763003;44719.776401117786;44818.77590612026;45587.316190257836;45703.314450283935;44010.11979760323;44875.551244487135;44984.77507612441;45761.31358029565;46219.76890115528;44730;45341.093178136915;46038.30942535798;46323.30515042209;47673.52326476829;37171.44242836306;40302.19395612013;40400.39399408952;40676.3898541528;40966.38550421806;29679.85160074186;29732.851335743184;29983;30143;30680.693193067782 10:32:16 INFO - PROCESS | 6814 | |2;jquery.html;399.2492918552148;420.91160856220193;430.18909355864184;435.0776354129246;435.57748983486016;16020.67958640828;20411.387658370237;20935.37193884184;20945.581088378225;21544.13823447064;19894.60210795783;19901.3034543791;19959.401217963485;19962.60074798503;20254.885981271025;5776.249087618604;5935.732892019859;5966.194563733906;6016.42843929826;6041;5904.232449781543;5991.610545314563;5992.9700351498395;5995.250593675791;5999.910001349983;5785.884282314351;5984.162217289583;5984.5212383009475;6005.87988240235;6012.158297838306;5808.215890854723;5977.043673012297;6007.128966299884;6024.548158888079;6069.848253793669;5394.78420863169;5424.701641409721;5425.782968681248;5430.5927055471;5432.049391356536;20487.28294509685;23516.76483235163;23988.760112398828;24231.636525452133;24414.755852441427;2618.48939456806;2821.4216085702383;2962.896298629538;3236.64396916339;3358.630550639428;14838.925805371013;14939.70120597582;15024.399024039025;15090.39638414462;15147.54557363281;1979.9505012374807;1992.232990298731;2120.703101565782;2124.743999242585;2129.488922658551;60771.39228607702;65619.3438065618;75929.86105208482;83995.58002210011;84277.57861210716;10895.618653347095;11392.373419461843;11414.94292528532;11550.711232219093;11765.941170294094;16539.25573349202;16549.338026479047;16558.6688266234;16571.25429355682;16575.751363729552;8803.295736341077;9061.365704400761;9169.495677737656;9176.31177661678;9223.677171298972;9041.367104302768;9041.412308199946;9043.276537876955;9109.863352049724;9266.444013359085;7000.824979375557;7038.6480675965995;7092.59167615189;7141.857162856818;7180.7845764627145;1969.2319995201883;2034.9593008139755;2063.6801295798873;2080.708700781892;2094.8533602647667;822.6421506644629;969.1665167955553;1042.7549525861416;1052.0636633396284;1113.8551988241638;20201;20987.58024839525;21313.786862131492;21405.78594214069;21424.892875535523;1020.0360659177155;1044.8693913260843;1052.1687866585312;1065.5950738719391;1075.7525769073156;11657.708557285967;12176.224913941067;12405.503779848705;12627.116101872964;12627.368631568568;402357;403159;406596;561493;586074;102209.48895255476;106070;109045.45477272721;110427;110918;1057.2916146182076;1059.9099076578423;1066.834640630688;1067.140951534007;1067.7330667333167;18969.620607587494;19164.329248476235;19269;19326.323578674677;19411.51471213231;32726.50910236396;40797;43754;44307.77846110749;45438.54561454343;63801.680991594745;64119.358806411336;64846.35153648592;65153.67423162854;65265.34734652783;9205.585748641193;9245.260379169516;9401.10689484498;9403.482808445327;9533.380330278509;10199.898001019896;13461.125026873222;13754.381052852446;14134.78797818013;14661.70676586441;8827.823443530964;11876.346800925687;11880.227785193934;12433.316167610868;12601.306928118674;7622.961885190539;8033.799155021171;12262.019038476721;12375.876241237473;12421.813672794735;7374.520656157332;12474;12579.559715410283;12865.614031579067;13043.934780326037;9134.771630709285;9349.065093490868;9469.715908522756;9632.662856799978;9731.318807683394;11358.60244891425;12380.380980951097;12542.24746515212;12565;12662.873371266538 10:32:16 INFO - PROCESS | 6814 | |3;mootools.html;1540.48393788081;1540.5378386484058;1699;1827.972580411295;1828.076821205291;9042.773930651738;9719.95140024299;9722.465264410472;9723.70828875134;9727.22182225422;8308.376871734628;8839.425437346585;9015.413998090136;9043.909560904389;9566.904330956688;2331.4404542909706;2335.532893421314;2337.5792357375667;2341.906323747048;2357.1278626907983;2317.44381348476;2319.5476882008015;2363.6690863279155;2364.3970787449175;2364.586197415454;2337.9649305260436;2338.228384633067;2339.7192336919593;2360.9409764755846;2376.6316220985686;2230.832687548424;2249.898754556041;2257.7064981552376;2258.446680563256;2276.1578216059997;2237.172246268891;2247.1685476373846;2248.2468373095035;2249.741279752817;2249.763774803655;9347.579358928864;9411.529423528797;9419.24646028316;9427.34008619397;9432.245420366422;5172;5351.866203344908;5357.4106848246665;5360.303160589098;5361.919571206435;3253.6258330292085;3335.6164041135094;3435.2957643682985;3438.7249020078584;3446.6897979181745;1090.432974853072;1103.0844399148687;1112.9499172537255;1117.1844553475926;1140.5950887435008;13381.19712817234;13456;13489.392977316043;13495.59513214605;13499.527516536973;4512.706674066175;4518.6611004174165;4717.198076327033;4718;4733.644976626767;11107.278026928385;11120.22158448917;11156.219064665558;11185.776284474265;11196.66410007701;5424.755885985141;5464.562834973192;5465.4261302563455;5467.917981230285;5483.150111732688;4523.705959112647;4885.975570122127;4972.254161875675;5042.974785126051;5089.618278629117;4106.299135412789;4134.545200028055;4206.978965105155;4226.746395216297;4245.80893859777;1259.2387104444533;1268.1630124118037;1288.2335010668637;1291.7029083310704;1298.746744384836;1021.8518314844306;1089.8310761831929;1090.5692251560747;1097.5390336058874;1100.2023532938556;6529.118568993125;6666.566673166229;6712.328767123248;6731.124953755981;6875.621840798804;450.80615335405435;456.915470637933;458.7453963050472;460.99078018439445;465.51353835242253;4644.5123262056995;4674.532546745366;4714.66997310185;4736.786844592001;4823.589994850477;11162.553497860157;11641.53433862636;11660.941695291469;11672.066234701204;11698.649040528628;11288.548458061585;11594.6521604352;11664.825027624593;11731.648050558497;12088.09339299556;8745.256653184551;8934.195922366887;8959.1936725695;8972.057933916974;8983.236424903957;6699.43054840344;6909.136357955255;6939.653017349011;7025.578465292099;7065.682044307916;11040.613578524713;11254.943725281648;11331.433428328715;11332.37671928019;11338.773224535298;11104.111671066457;11119.332840029625;11289.887101129212;11344.262622929484;11458.885411145782;7795.57124358143;8345.83308333842;8428.45215061019;8581.613827377907;8683.696070637498;9486.762830929281;9528.237740980843;9552.23582113443;9554.570044347882;9555.378900371454;8321.417500774887;8341.20758527939;8349;8425.325973921948;8436.198561136682;7993.12075671664;8042.83914321722;8049.557274349967;8076.798080047811;8288.958555207186;6850.075239842757;6978.930210698031;7053.329933656294;7439.628018599156;7509.249075092447;5428.37573679032;5476.342838859203;5494.890102198014;5522.66863988162;5551.25058117149;5495.422980586981;5569.248151499496;5655.575831812548;5686.744096515749;5797.652140871562 10:32:16 INFO - PROCESS | 6814 | |4;prototype.html;349.32405794787087;356.839422259983;358.2709186804852;363.67632806801936;365.1328095772542;5549.667019978804;5575.69333686647;5614.3543492498375;5633.887322253558;5656.151577263413;5172.612054095939;5226.7909283628705;5458.61789674723;5503.174523821429;5511.677197472608;4223.788810559475;4267.381229721688;4270.295401258789;4280.272353699878;4293.677974151936;4210.768407737573;4261.680373971949;4268.125034367963;4280.9143817123795;4286.485621725397;4154.605312495308;4233.449651545295;4255.38295447731;4282.400463935051;4296.656267498608;4245.61789438952;4256.765877876715;4281.293586558214;4296.505901821283;4310.525842157376;3785.962140378616;3797.8290976906105;3799.449079883429;3803.885883423474;3815.8855234343014;5236.81671141512;5353.304070470853;5359.651622644516;5375.865603359908;5380.65941315529;4904.215325547895;4912.38595175603;4933.593925731149;4935.605151587864;4948.480409557017;5697.77208911643;5715.342735585419;5730.512906402961;5736.483716465496;5737.598368114233;4085.9387109193385;4136.138737568106;4168.91662166758;4171.165766846643;4194.832206711728;5896.110905656241;5904.409559044104;5914.260717410324;5933.376995415462;5937.851553711105;4272.230998420252;4288.91422171561;4289.67827412939;4299;4299.1831552005015;4088.5911408858874;4309.633681137076;4339.631131353807;4347.695661303678;4358.934615980763;4886;4891.804327826918;4898.53463920927;4900.338454308694;4907.895723462264;5737.827865164052;5742.329816635284;5751.827445176651;5785.392533783976;5790.478856902899;1439.79842822005;1453.6438572549794;1466.8019817324525;1487;1492.574616234368;983.8475036369374;1032.509557959978;1050.837120246363;1059.4808543813476;1064.8615679961556;5518.255035570227;5518.33779946401;5528.834134975958;5606.327240731139;5614.6631202128;5749.913751293734;5881.176635271066;5884.499817515559;5890.79238756054;5895.140856684505;5465.289512363367;5527.972360138254;5536.474034966672;5541.722913854291;5568.276124103922;3091.7835751497178;3103.8515749172952;3121.4225368306934;3127.9365015894705;3131.1545882611567;5698.344690360579;5952.136940143655;5955.3151387590115;5969.552283578749;5973.163757074013;5840.685845684687;5840.8539786504825;5861.834609311009;5916.497097746654;5936.308152176609;5787.2187254720075;5968.940310596839;5982.282126144891;6003.0695242237525;6015.3383127855905;5790.710464476675;5801.8549536261935;5802.94197058024;5808.796692115756;5830.883382332415;5654.519365853948;5657.886842263214;5666.660000399989;5671.716414179357;5724.427557244242;5762.4525670061275;5935.821925342247;5941.613795103304;6071.089336599369;6075.908861366995;4152.522459917148;4160.958390416057;4174.937375939303;4184.81168347419;4186.016286172686;5878.94121058801;5939.346671866005;5943.197668314895;5953.10703394477;5966.791162309299;5647.3010969862125;5778.711064446677;5793.493691640119;5815.621984570989;5820.370296317038;5750.827475175752;5757;5758.568107391879;5764.654120752768;5767.163761254511;5191.247269146028;5454.4272851351625;5517.282753242213;5746.281714785652;5761.8559536011935;5782.710864456844;5787.929233091892;5815.563832712479;5818.73815678304;5830.387809279964;5671.631343962629;5673.858153546029;5696.515796157373;5705.546614955609;5709.857253568694 10:32:16 INFO - PROCESS | 6814 | |5;yui.html;806.4717111854338;813.6135335715536;851.6040041380758;865.8484765166103;894.3292530602048;14658.193799341045;15789.842101578995;15794.526164215093;15815.762763558556;15838.049717016987;14688.706225875476;14724.631884202874;14738.410463581444;14766.261686915665;14770.778438323434;701.484408959417;834.6995081770558;851.5997481183849;868.5179725252482;869.2871845087031;864.7016779211193;867.6702852915884;867.8698195270729;870.8214815962713;875.2866413872673;916.3081873185736;917.1149840404008;917.5962576466334;918.1140199707281;918.5820451694462;912.2610685344891;912.7124955638985;913.4245425382001;913.8720579118929;914.0950459045564;673.5049738442241;680.1328306409367;681.1009467502898;681.6012632609929;682.1234713393266;14364.92817535916;14371.640708982255;14390.136591804525;14395.568132956028;14418.639534011734;16300;16325.59186020359;16493.092879891603;16870.409535666193;16926.746098808406;12840.165389249669;12859;13028.088033837636;13184;13443.798343024862;3947.605239476058;3959.703022273341;4017.337139372;4040.939385909214;4083.183363327317;85629.57185214096;88079.55960220222;88813.99999999936;89358.5532072342;91231.54384228103;12365.505379784889;12448.190867593577;12526.749465010831;12606.55877044299;12704.74590508203;24229.15197968098;24407.26778196657;24443.51112977766;24544.386390340027;24626.5074698505;8965.686200982935;9209.35534512591;9213.894332680122;9276.582553785096;9277.489738064336;4912.754362281871;5000.149974504343;5095.8980820383385;5207.713575753294;5236.214567814858;3668.1013151777984;3795.0891785971166;3796.3925771876366;3804.201117765245;3805.7145714071557;611.9357467465941;621.1366200980641;678.4097834883682;716.2336300158884;727.8362368467158;696.5785699651638;770.4221833624781;799.6801279488135;801.9318357939524;804.2399932064143;10249.641262555924;10307.433091179908;10677.733056673645;11073.612423565297;11119.44402779874;620.5159975219336;702.2626242445411;754.6604028187338;768.2432803688363;770.7533589251386;4829.154897892855;4993.325901003391;5131.948680513222;5132.076226279306;5144.536991670768;120377.39811300888;122581.77418225881;123483;124243;129038;67217.66391168012;101367.49316253519;103506.48246758718;104540.47729761302;108715.45642271738;93933.53033234927;95754.52122739481;99814.50092749635;101320.49339753254;102463.4876825626;9779.706608801747;9783.217342612716;9931.702048938543;10126.645567405105;10162.644307449204;39398.212035759694;51303.743481282356;53380.46619533755;54192;62199;49499.25751113808;67273.66363168153;75655.59549820161;75834.62082689551;77450;13096.476140954255;13099.93450032782;13136.27750473733;13448.663783405493;14501.782473262698;14880.404783808526;15151.696966060837;15263.160526171167;15768.526944191693;16096.195190240674;14650.340734666752;14763.203471821262;15005;15371.692566148391;15454.84545154579;13680.247586382844;14403.207823569805;15106.471273505374;15334.616634584225;16065.839341606434;13308.800367994294;13531.729365412833;13765.105268157538;14309.928450357682;14421.134731916118;10881.510332035235;11066.889331106586;11306.886931130912;11435.885641143483;11855.703607409883;11129.165312601426;11844.526218951145;11844.76310473803;11985.28088314704;12220.205686630696 10:32:16 INFO - PROCESS | 6814 | __end_tp_report 10:32:16 INFO - PROCESS | 6814 | __start_cc_report 10:32:16 INFO - PROCESS | 6814 | _x_x_mozilla_cycle_collect,1002 10:32:16 INFO - PROCESS | 6814 | __end_cc_report 10:32:16 INFO - PROCESS | 6814 | __startTimestamp1474651936018__endTimestamp 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | ------- Summary: start ------- 10:32:16 INFO - PROCESS | 6814 | Number of tests: 6 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | [#0] dojo.html Cycles:165 Average:5562.58 Median:5778.28 stddev:629.18 (10.9%) stddev-sans-first:602.54 10:32:16 INFO - PROCESS | 6814 | Values: 3173.2 3208.8 3223.5 3252.4 3264.2 5517.9 5600.2 5619.7 5629.0 5636.9 5609.1 5644.1 5645.5 5645.6 5686.8 5032.1 5050.9 5099.6 5114.8 5171.4 5041.6 5089.6 5106.5 5131.8 5176.5 5126.1 5195.9 5200.4 5202.2 5204.2 5137.8 5191.6 5191.8 5196.3 5231.9 4919.8 4956.6 5074.2 5092.9 5102.2 5526.3 5530.0 5543.2 5543.8 5579.5 5917.6 5960.4 5974.6 5974.9 5979.8 5831.8 5897.8 5904.4 5941.6 6049.6 5623.7 5628.2 5647.9 5656.2 5657.0 6134.0 6139.6 6141.7 6144.9 6151.7 5696.5 5819.9 5864.7 5878.7 5977.0 6011.0 6015.5 6017.9 6025.4 6027.9 5981.5 5991.6 6001.3 6002.5 6007.0 5710.9 5861.0 5895.8 5897.4 5961.0 4586.4 4586.9 4587.4 4589.4 4613.9 4089.3 4094.5 4099.1 4101.1 4130.2 5487.6 5639.2 5656.1 5676.2 5751.5 6037.5 6046.6 6047.0 6050.3 6052.2 5715.3 5716.1 5741.1 5746.7 5746.7 4971.5 5018.4 5044.1 5054.1 5126.5 6228.1 6231.1 6237.6 6238.9 6243.0 6005.2 6037.3 6057.5 6058.9 6068.2 6014.0 6075.9 6116.5 6142.8 6183.2 5913.6 5919.1 5921.2 5928.9 5957.6 6011.6 6028.8 6030.0 6035.5 6037.5 5650.5 5738.4 5781.4 5809.6 5876.8 5819.1 5824.0 5826.2 5830.4 5830.4 5739.9 5740.2 5778.3 5788.7 5796.8 5705.0 5820.1 5896.8 5897.0 5959.2 5804.2 5804.7 5809.0 5810.6 5842.5 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | [#1] ext.html Cycles:180 Average:72174.55 Median:41369.38 stddev:157907.43 (381.7%) stddev-sans-first:158258.98 10:32:16 INFO - PROCESS | 6814 | Values: 606.8 639.1 658.5 666.2 671.2 34961.0 37901.4 37925.8 37936.8 46060.0 44380.3 48787.3 48821.0 48894.3 48923.5 9186.4 9346.9 9348.8 9359.3 9368.3 8628.5 8834.0 9195.9 9301.2 9304.8 11039.3 11244.1 11325.2 11771.3 11779.6 11246.7 11273.9 11284.3 11313.3 11323.8 11117.9 11177.2 11194.5 11199.2 11200.7 46145.1 46334.5 46364.5 46383.8 46387.3 3892.8 3925.4 4022.6 4065.0 4319.1 36770.4 36780.8 36906.6 39161.2 40083.6 4559.3 4632.5 4740.9 4760.6 4847.3 394810.0 401313.0 401563.0 401683.0 402996.0 40062.0 41092.4 68838.7 69968.3 70020.0 41646.4 42056.4 42109.2 42119.0 42159.0 17928.3 17991.6 18094.9 18104.4 18106.5 15290.9 15418.5 15421.2 15426.6 15434.7 9037.8 9335.4 9527.4 9576.0 9645.9 3662.3 3685.4 3719.4 3720.8 3725.8 1056.5 1056.5 1071.9 1135.5 1171.9 34778.7 35114.5 35308.6 35422.3 35565.5 1160.4 1161.0 1206.4 1213.6 1250.5 17714.6 18732.9 19307.3 19723.0 20130.2 622297.0 626969.0 627822.0 929870.0 930856.0 67049.3 67235.3 67691.0 67737.7 67767.0 1507.1 1515.1 1516.6 1535.1 1547.6 35273.5 36677.0 36692.6 37049.6 37112.6 57649.4 58441.4 58441.7 58464.7 58481.4 542912.0 548311.0 548717.0 549162.0 551377.0 36162.0 42098.6 45072.0 49770.3 50327.2 50087.2 51327.0 51474.5 51559.5 52407.5 44077.1 44719.8 44818.8 45587.3 45703.3 44010.1 44875.6 44984.8 45761.3 46219.8 44730.0 45341.1 46038.3 46323.3 47673.5 37171.4 40302.2 40400.4 40676.4 40966.4 29679.9 29732.9 29983.0 30143.0 30680.7 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | [#2] jquery.html Cycles:180 Average:29500.06 Median:23752.76 stddev:79407.32 (334.3%) stddev-sans-first:79600.02 10:32:16 INFO - PROCESS | 6814 | Values: 399.2 420.9 430.2 435.1 435.6 16020.7 20411.4 20935.4 20945.6 21544.1 19894.6 19901.3 19959.4 19962.6 20254.9 5776.2 5935.7 5966.2 6016.4 6041.0 5904.2 5991.6 5993.0 5995.3 5999.9 5785.9 5984.2 5984.5 6005.9 6012.2 5808.2 5977.0 6007.1 6024.5 6069.8 5394.8 5424.7 5425.8 5430.6 5432.0 20487.3 23516.8 23988.8 24231.6 24414.8 2618.5 2821.4 2962.9 3236.6 3358.6 14838.9 14939.7 15024.4 15090.4 15147.5 1980.0 1992.2 2120.7 2124.7 2129.5 60771.4 65619.3 75929.9 83995.6 84277.6 10895.6 11392.4 11414.9 11550.7 11765.9 16539.3 16549.3 16558.7 16571.3 16575.8 8803.3 9061.4 9169.5 9176.3 9223.7 9041.4 9041.4 9043.3 9109.9 9266.4 7000.8 7038.6 7092.6 7141.9 7180.8 1969.2 2035.0 2063.7 2080.7 2094.9 822.6 969.2 1042.8 1052.1 1113.9 20201.0 20987.6 21313.8 21405.8 21424.9 1020.0 1044.9 1052.2 1065.6 1075.8 11657.7 12176.2 12405.5 12627.1 12627.4 402357.0 403159.0 406596.0 561493.0 586074.0 102209.5 106070.0 109045.5 110427.0 110918.0 1057.3 1059.9 1066.8 1067.1 1067.7 18969.6 19164.3 19269.0 19326.3 19411.5 32726.5 40797.0 43754.0 44307.8 45438.5 63801.7 64119.4 64846.4 65153.7 65265.3 9205.6 9245.3 9401.1 9403.5 9533.4 10199.9 13461.1 13754.4 14134.8 14661.7 8827.8 11876.3 11880.2 12433.3 12601.3 7623.0 8033.8 12262.0 12375.9 12421.8 7374.5 12474.0 12579.6 12865.6 13043.9 9134.8 9349.1 9469.7 9632.7 9731.3 11358.6 12380.4 12542.2 12565.0 12662.9 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | [#3] mootools.html Cycles:180 Average:6203.27 Median:459.87 stddev:3647.94 (793.3%) stddev-sans-first:3641.35 10:32:16 INFO - PROCESS | 6814 | Values: 1540.5 1540.5 1699.0 1828.0 1828.1 9042.8 9720.0 9722.5 9723.7 9727.2 8308.4 8839.4 9015.4 9043.9 9566.9 2331.4 2335.5 2337.6 2341.9 2357.1 2317.4 2319.5 2363.7 2364.4 2364.6 2338.0 2338.2 2339.7 2360.9 2376.6 2230.8 2249.9 2257.7 2258.4 2276.2 2237.2 2247.2 2248.2 2249.7 2249.8 9347.6 9411.5 9419.2 9427.3 9432.2 5172.0 5351.9 5357.4 5360.3 5361.9 3253.6 3335.6 3435.3 3438.7 3446.7 1090.4 1103.1 1112.9 1117.2 1140.6 13381.2 13456.0 13489.4 13495.6 13499.5 4512.7 4518.7 4717.2 4718.0 4733.6 11107.3 11120.2 11156.2 11185.8 11196.7 5424.8 5464.6 5465.4 5467.9 5483.2 4523.7 4886.0 4972.3 5043.0 5089.6 4106.3 4134.5 4207.0 4226.7 4245.8 1259.2 1268.2 1288.2 1291.7 1298.7 1021.9 1089.8 1090.6 1097.5 1100.2 6529.1 6666.6 6712.3 6731.1 6875.6 450.8 456.9 458.7 461.0 465.5 4644.5 4674.5 4714.7 4736.8 4823.6 11162.6 11641.5 11660.9 11672.1 11698.6 11288.5 11594.7 11664.8 11731.6 12088.1 8745.3 8934.2 8959.2 8972.1 8983.2 6699.4 6909.1 6939.7 7025.6 7065.7 11040.6 11254.9 11331.4 11332.4 11338.8 11104.1 11119.3 11289.9 11344.3 11458.9 7795.6 8345.8 8428.5 8581.6 8683.7 9486.8 9528.2 9552.2 9554.6 9555.4 8321.4 8341.2 8349.0 8425.3 8436.2 7993.1 8042.8 8049.6 8076.8 8289.0 6850.1 6978.9 7053.3 7439.6 7509.2 5428.4 5476.3 5494.9 5522.7 5551.3 5495.4 5569.2 5655.6 5686.7 5797.7 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | [#4] prototype.html Cycles:180 Average:4830.75 Median:5528.40 stddev:1401.35 (25.3%) stddev-sans-first:1364.31 10:32:16 INFO - PROCESS | 6814 | Values: 349.3 356.8 358.3 363.7 365.1 5549.7 5575.7 5614.4 5633.9 5656.2 5172.6 5226.8 5458.6 5503.2 5511.7 4223.8 4267.4 4270.3 4280.3 4293.7 4210.8 4261.7 4268.1 4280.9 4286.5 4154.6 4233.4 4255.4 4282.4 4296.7 4245.6 4256.8 4281.3 4296.5 4310.5 3786.0 3797.8 3799.4 3803.9 3815.9 5236.8 5353.3 5359.7 5375.9 5380.7 4904.2 4912.4 4933.6 4935.6 4948.5 5697.8 5715.3 5730.5 5736.5 5737.6 4085.9 4136.1 4168.9 4171.2 4194.8 5896.1 5904.4 5914.3 5933.4 5937.9 4272.2 4288.9 4289.7 4299.0 4299.2 4088.6 4309.6 4339.6 4347.7 4358.9 4886.0 4891.8 4898.5 4900.3 4907.9 5737.8 5742.3 5751.8 5785.4 5790.5 1439.8 1453.6 1466.8 1487.0 1492.6 983.8 1032.5 1050.8 1059.5 1064.9 5518.3 5518.3 5528.8 5606.3 5614.7 5749.9 5881.2 5884.5 5890.8 5895.1 5465.3 5528.0 5536.5 5541.7 5568.3 3091.8 3103.9 3121.4 3127.9 3131.2 5698.3 5952.1 5955.3 5969.6 5973.2 5840.7 5840.9 5861.8 5916.5 5936.3 5787.2 5968.9 5982.3 6003.1 6015.3 5790.7 5801.9 5802.9 5808.8 5830.9 5654.5 5657.9 5666.7 5671.7 5724.4 5762.5 5935.8 5941.6 6071.1 6075.9 4152.5 4161.0 4174.9 4184.8 4186.0 5878.9 5939.3 5943.2 5953.1 5966.8 5647.3 5778.7 5793.5 5815.6 5820.4 5750.8 5757.0 5758.6 5764.7 5767.2 5191.2 5454.4 5517.3 5746.3 5761.9 5782.7 5787.9 5815.6 5818.7 5830.4 5671.6 5673.9 5696.5 5705.5 5709.9 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - PROCESS | 6814 | [#5] yui.html Cycles:180 Average:21991.12 Median:3800.30 stddev:31910.08 (839.7%) stddev-sans-first:31959.95 10:32:16 INFO - PROCESS | 6814 | Values: 806.5 813.6 851.6 865.8 894.3 14658.2 15789.8 15794.5 15815.8 15838.0 14688.7 14724.6 14738.4 14766.3 14770.8 701.5 834.7 851.6 868.5 869.3 864.7 867.7 867.9 870.8 875.3 916.3 917.1 917.6 918.1 918.6 912.3 912.7 913.4 913.9 914.1 673.5 680.1 681.1 681.6 682.1 14364.9 14371.6 14390.1 14395.6 14418.6 16300.0 16325.6 16493.1 16870.4 16926.7 12840.2 12859.0 13028.1 13184.0 13443.8 3947.6 3959.7 4017.3 4040.9 4083.2 85629.6 88079.6 88814.0 89358.6 91231.5 12365.5 12448.2 12526.7 12606.6 12704.7 24229.2 24407.3 24443.5 24544.4 24626.5 8965.7 9209.4 9213.9 9276.6 9277.5 4912.8 5000.1 5095.9 5207.7 5236.2 3668.1 3795.1 3796.4 3804.2 3805.7 611.9 621.1 678.4 716.2 727.8 696.6 770.4 799.7 801.9 804.2 10249.6 10307.4 10677.7 11073.6 11119.4 620.5 702.3 754.7 768.2 770.8 4829.2 4993.3 5131.9 5132.1 5144.5 120377.4 122581.8 123483.0 124243.0 129038.0 67217.7 101367.5 103506.5 104540.5 108715.5 93933.5 95754.5 99814.5 101320.5 102463.5 9779.7 9783.2 9931.7 10126.6 10162.6 39398.2 51303.7 53380.5 54192.0 62199.0 49499.3 67273.7 75655.6 75834.6 77450.0 13096.5 13099.9 13136.3 13448.7 14501.8 14880.4 15151.7 15263.2 15768.5 16096.2 14650.3 14763.2 15005.0 15371.7 15454.8 13680.2 14403.2 15106.5 15334.6 16065.8 13308.8 13531.7 13765.1 14309.9 14421.1 10881.5 11066.9 11306.9 11435.9 11855.7 11129.2 11844.5 11844.8 11985.3 12220.2 10:32:16 INFO - PROCESS | 6814 | -------- Summary: end -------- 10:32:16 INFO - PROCESS | 6814 | 10:32:16 INFO - TEST-INFO | 6814: exit 0 10:32:16 INFO - TEST-OK | dromaeo_css | took 1113000ms 10:32:16 INFO - TEST-START | kraken 10:32:16 INFO - operating with platform_type : linux_ 10:32:16 INFO - Initialising browser for kraken test... 10:32:16 INFO - TEST-INFO | started process 7008 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwa0Sgu/profile http://localhost:35655/getInfo.html) 10:32:20 INFO - PROCESS | 7008 | __metrics Screen width/height:1600/1200 10:32:20 INFO - PROCESS | 7008 | colorDepth:24 10:32:20 INFO - PROCESS | 7008 | Browser inner width/height: 1024/697 10:32:20 INFO - PROCESS | 7008 | __metrics 10:32:21 INFO - PROCESS | 7008 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:32:21 INFO - PROCESS | 7008 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:32:22 INFO - PROCESS | 7008 | console.error: PushService: 10:32:22 INFO - PROCESS | 7008 | stateChangeProcessEnqueue: Error transitioning state 10:32:22 INFO - PROCESS | 7008 | UnknownError 10:32:22 INFO - TEST-INFO | 7008: exit 0 10:32:22 INFO - Browser initialized. 10:32:22 INFO - Running cycle 1/1 for kraken test... 10:32:22 INFO - TEST-INFO | started process 7097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwa0Sgu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:32:34 INFO - PROCESS | 7097 | RSS: Main: 143724544 10:32:34 INFO - PROCESS | 7097 | 10:32:37 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35655/tests/kraken/driver.html?testName=audio-beat-detection) 10:32:37 INFO - PROCESS | 7097 | RSS: Main: 198852608 10:32:37 INFO - PROCESS | 7097 | 10:32:40 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35655/tests/kraken/driver.html?testName=audio-dft) 10:32:41 INFO - PROCESS | 7097 | RSS: Main: 183300096 10:32:41 INFO - PROCESS | 7097 | 10:32:43 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35655/tests/kraken/driver.html?testName=audio-fft) 10:32:44 INFO - PROCESS | 7097 | RSS: Main: 274743296 10:32:44 INFO - PROCESS | 7097 | 10:32:46 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35655/tests/kraken/driver.html?testName=audio-oscillator) 10:32:47 INFO - PROCESS | 7097 | RSS: Main: 180121600 10:32:47 INFO - PROCESS | 7097 | 10:32:49 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35655/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:32:49 INFO - PROCESS | 7097 | RSS: Main: 178282496 10:32:49 INFO - PROCESS | 7097 | 10:32:52 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35655/tests/kraken/driver.html?testName=imaging-darkroom) 10:32:53 INFO - PROCESS | 7097 | RSS: Main: 214757376 10:32:53 INFO - PROCESS | 7097 | 10:32:56 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35655/tests/kraken/driver.html?testName=imaging-desaturate) 10:32:57 INFO - PROCESS | 7097 | RSS: Main: 213762048 10:32:57 INFO - PROCESS | 7097 | 10:33:00 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35655/tests/kraken/driver.html?testName=json-parse-financial) 10:33:00 INFO - PROCESS | 7097 | RSS: Main: 212832256 10:33:00 INFO - PROCESS | 7097 | 10:33:02 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35655/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:33:02 INFO - PROCESS | 7097 | RSS: Main: 175489024 10:33:02 INFO - PROCESS | 7097 | 10:33:04 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:33:05 INFO - PROCESS | 7097 | RSS: Main: 182947840 10:33:05 INFO - PROCESS | 7097 | 10:33:07 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:33:07 INFO - PROCESS | 7097 | RSS: Main: 183988224 10:33:07 INFO - PROCESS | 7097 | 10:33:10 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:33:10 INFO - PROCESS | 7097 | RSS: Main: 185421824 10:33:10 INFO - PROCESS | 7097 | 10:33:13 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:33:14 INFO - PROCESS | 7097 | RSS: Main: 183070720 10:33:14 INFO - PROCESS | 7097 | 10:33:15 INFO - PROCESS | 7097 | Cycle 1(1): loaded http://localhost:35655/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35655/tests/kraken/driver.html?testName=ai-astar) 10:33:16 INFO - PROCESS | 7097 | RSS: Main: 234631168 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | __start_tp_report 10:33:16 INFO - PROCESS | 7097 | _x_x_mozilla_page_load 10:33:16 INFO - PROCESS | 7097 | _x_x_mozilla_page_load_details 10:33:16 INFO - PROCESS | 7097 | |i|pagename|runs| 10:33:16 INFO - PROCESS | 7097 | |0;ai-astar;109;111;120;110;116;109;108;110;111;109 10:33:16 INFO - PROCESS | 7097 | |1;audio-beat-detection;144;114;140;164;120;140;146;121;176;115 10:33:16 INFO - PROCESS | 7097 | |2;audio-dft;159;160;167;161;161;160;160;160;161;160 10:33:16 INFO - PROCESS | 7097 | |3;audio-fft;75;74;75;188;76;75;75;75;169;75 10:33:16 INFO - PROCESS | 7097 | |4;audio-oscillator;83;83;166;84;84;84;151;82;83;149 10:33:16 INFO - PROCESS | 7097 | |5;imaging-gaussian-blur;116;116;116;118;118;116;116;116;117;117 10:33:16 INFO - PROCESS | 7097 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;145;147 10:33:16 INFO - PROCESS | 7097 | |7;imaging-desaturate;94;94;94;94;94;95;94;95;101;94 10:33:16 INFO - PROCESS | 7097 | |8;json-parse-financial;51;50;51;50;49;49;49;51;51;49 10:33:16 INFO - PROCESS | 7097 | |9;json-stringify-tinderbox;59;60;60;60;60;60;59;61;61;59 10:33:16 INFO - PROCESS | 7097 | |10;stanford-crypto-aes;73;72;76;73;78;72;72;72;72;72 10:33:16 INFO - PROCESS | 7097 | |11;stanford-crypto-ccm;125;125;123;131;116;126;115;126;156;117 10:33:16 INFO - PROCESS | 7097 | |12;stanford-crypto-pbkdf2;150;155;153;154;153;161;153;153;157;152 10:33:16 INFO - PROCESS | 7097 | |13;stanford-crypto-sha256-iterative;65;68;63;62;65;62;62;60;60;61 10:33:16 INFO - PROCESS | 7097 | __end_tp_report 10:33:16 INFO - PROCESS | 7097 | __start_cc_report 10:33:16 INFO - PROCESS | 7097 | _x_x_mozilla_cycle_collect,1704 10:33:16 INFO - PROCESS | 7097 | __end_cc_report 10:33:16 INFO - PROCESS | 7097 | __startTimestamp1474651996087__endTimestamp 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | ------- Summary: start ------- 10:33:16 INFO - PROCESS | 7097 | Number of tests: 14 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#0] ai-astar Cycles:10 Average:111.30 Median:110.50 stddev:3.77 (3.4%) stddev-sans-first:3.91 10:33:16 INFO - PROCESS | 7097 | Values: 109.0 111.0 120.0 110.0 116.0 109.0 108.0 110.0 111.0 109.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:142.00 stddev:20.94 (14.7%) stddev-sans-first:22.10 10:33:16 INFO - PROCESS | 7097 | Values: 144.0 114.0 140.0 164.0 120.0 140.0 146.0 121.0 176.0 115.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#2] audio-dft Cycles:10 Average:160.90 Median:160.50 stddev:2.23 (1.4%) stddev-sans-first:2.26 10:33:16 INFO - PROCESS | 7097 | Values: 159.0 160.0 167.0 161.0 161.0 160.0 160.0 160.0 161.0 160.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#3] audio-fft Cycles:10 Average:95.70 Median:75.00 stddev:43.87 (58.5%) stddev-sans-first:45.89 10:33:16 INFO - PROCESS | 7097 | Values: 75.0 74.0 75.0 188.0 76.0 75.0 75.0 75.0 169.0 75.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#4] audio-oscillator Cycles:10 Average:104.90 Median:83.00 stddev:35.08 (42.3%) stddev-sans-first:36.30 10:33:16 INFO - PROCESS | 7097 | Values: 83.0 83.0 166.0 84.0 84.0 84.0 151.0 82.0 83.0 149.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:116.50 stddev:0.84 (0.7%) stddev-sans-first:0.87 10:33:16 INFO - PROCESS | 7097 | Values: 116.0 116.0 116.0 118.0 118.0 116.0 116.0 116.0 117.0 117.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 10:33:16 INFO - PROCESS | 7097 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 147.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:2.18 (2.3%) stddev-sans-first:2.29 10:33:16 INFO - PROCESS | 7097 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 101.0 94.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.50 stddev:0.94 (1.9%) stddev-sans-first:0.93 10:33:16 INFO - PROCESS | 7097 | Values: 51.0 50.0 51.0 50.0 49.0 49.0 49.0 51.0 51.0 49.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 10:33:16 INFO - PROCESS | 7097 | Values: 59.0 60.0 60.0 60.0 60.0 60.0 59.0 61.0 61.0 59.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:72.50 stddev:2.10 (2.9%) stddev-sans-first:2.22 10:33:16 INFO - PROCESS | 7097 | Values: 73.0 72.0 76.0 73.0 78.0 72.0 72.0 72.0 72.0 72.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:125.50 stddev:11.73 (9.3%) stddev-sans-first:12.43 10:33:16 INFO - PROCESS | 7097 | Values: 125.0 125.0 123.0 131.0 116.0 126.0 115.0 126.0 156.0 117.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.10 Median:153.50 stddev:3.03 (2.0%) stddev-sans-first:2.83 10:33:16 INFO - PROCESS | 7097 | Values: 150.0 155.0 153.0 154.0 153.0 161.0 153.0 153.0 157.0 152.0 10:33:16 INFO - PROCESS | 7097 | 10:33:16 INFO - PROCESS | 7097 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:62.50 stddev:2.53 (4.0%) stddev-sans-first:2.55 10:33:16 INFO - PROCESS | 7097 | Values: 65.0 68.0 63.0 62.0 65.0 62.0 62.0 60.0 60.0 61.0 10:33:16 INFO - PROCESS | 7097 | -------- Summary: end -------- 10:33:16 INFO - PROCESS | 7097 | 10:33:17 INFO - TEST-INFO | 7097: exit 0 10:33:17 INFO - TEST-OK | kraken | took 60280ms 10:33:17 INFO - SUITE-END | took 1173s 10:33:17 INFO - Completed test suite (00:19:33) 10:33:17 INFO - javascript benchmark 10:33:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3173.1908363367343, 3208.791428557144, 3223.5487031815537, 3252.4470839957203, 3264.2165880188763, 5517.917231241529, 5600.243967064445, 5619.690916999569, 5628.9999999999945, 5636.943630563693, 5609.074502707059, 5644.068728659777, 5645.5483561315095, 5645.604807663465, 5686.829395118143, 5032.069067222557, 5050.898982020358, 5099.5665368443815, 5114.846554603368, 5171.4311425743135, 5041.596672266228, 5089.592832573385, 5106.489351064901, 5131.7690703918415, 5176.5341119299255, 5126.051680439112, 5195.9480405195845, 5200.427952925175, 5202.245674377213, 5204.193350030751, 5137.845864624067, 5191.584673226152, 5191.8442446726285, 5196.272521846944, 5231.869203269911, 4919.754012299407, 4956.628252881048, 5074.238864170404, 5092.949070509321, 5102.158143906251, 5526.336839579236, 5529.972350138223, 5543.196236545718, 5543.805966791183, 5579.525740312079, 5917.585768996172, 5960.374160713106, 5974.551908606828, 5974.850628734316, 5979.790707325266, 5831.825045248649, 5897.764089436418, 5904.409559044104, 5941.643501389931, 6049.606775559574, 5623.662580245198, 5628.183913332544, 5647.943520564783, 5656.179853921159, 5656.971715141439, 6134.04922237054, 6139.57023008394, 6141.723622437001, 6144.8771024580155, 6151.661658608819, 5696.544276457874, 5819.941800582024, 5864.706764661835, 5878.706064696748, 5977.043673012384, 6011.0, 6015.488683461955, 6017.879642407215, 6025.427584379477, 6027.9095813562835, 5981.5214782817275, 5991.610545314541, 6001.3098493672915, 6002.489788368038, 6006.969965150146, 5710.942890571041, 5861.0, 5895.764169433261, 5897.410258974069, 5961.0, 4586.449626044896, 4586.931196032062, 4587.3807036050375, 4589.380433641416, 4613.907721845544, 4089.284375234322, 4094.488188976378, 4099.057216840143, 4101.1387608601935, 4130.173965206911, 5487.6158668892795, 5639.182318563785, 5656.066748986371, 5676.233708449389, 5751.4536119068625, 6037.516998640098, 6046.637201767908, 6047.03247480401, 6050.30421501533, 6052.213212282375, 5715.342735585377, 5716.056850619601, 5741.110127930177, 5746.683932383759, 5746.712664366765, 4971.502849715, 5018.397792264878, 5044.1172794761505, 5054.064997975386, 5126.53861152498, 6228.128062071223, 6231.127642130105, 6237.625742455467, 6238.875222495525, 6242.968785156045, 6005.159277701213, 6037.335893051673, 6057.454829065229, 6058.878822423615, 6068.150458935665, 6014.0, 6075.908861366995, 6116.487945662109, 6142.78500252489, 6183.196184495987, 5913.615614985012, 5919.112133179885, 5921.171036055042, 5928.9407105928385, 5957.582969192288, 6011.639301641915, 6028.758849645965, 6030.03519436888, 6035.547333949883, 6037.547183961308, 5650.491455769001, 5738.397468265772, 5781.4218578141845, 5809.593328466966, 5876.823695289148, 5819.127130930395, 5823.970880145572, 5826.213461182686, 5830.416958304136, 5830.4461076197695, 5739.8852022960145, 5740.1963725077685, 5778.27771528559, 5788.7395067222915, 5796.768129274782, 5705.0, 5820.068788993741, 5896.7936122235515, 5897.0, 5959.165716799738, 5804.21643078179, 5804.709764511673, 5808.970955145197, 5810.564207684356, 5842.532597392284], "value": 5520.017155280622, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.7845914700281, 639.1499305923122, 658.4732214228617, 666.2105405094962, 671.1811589860368, 34961.0, 37901.431478527775, 37925.810370948144, 37936.810315948416, 46060.0, 44380.3342949856, 48787.26819097716, 48821.0, 48894.26658600115, 48923.510764892344, 9186.356955013152, 9346.859797103049, 9348.766280842965, 9359.344845860793, 9368.250539956787, 8628.525431101287, 8833.95583022084, 9195.908040919605, 9301.209397201246, 9304.81390372192, 11039.337639741601, 11244.100471962265, 11325.207235493519, 11771.293722376642, 11779.587714429998, 11246.66260012201, 11273.943630281878, 11284.266522676082, 11313.264637798517, 11323.830142547868, 11117.944410277896, 11177.161712871568, 11194.496247668874, 11199.160062995226, 11200.719982000433, 46145.077098458176, 46334.53665463336, 46364.53635463636, 46383.76808115972, 46387.30419043717, 3892.805359732002, 3925.411188321746, 4022.5776293489057, 4064.959350406488, 4319.07139964909, 36770.448443273104, 36780.81609591935, 36906.63093369059, 39161.21677566461, 40083.59916400857, 4559.338895860082, 4632.490426053131, 4740.881477963044, 4760.64295177863, 4847.297141914438, 394810.0, 401313.0, 401563.0, 401682.9999999971, 402996.0, 40062.0, 41092.38361424581, 68838.65580672065, 69968.30031699719, 70020.0, 41646.37530437046, 42056.36915446271, 42109.157816844105, 42119.0, 42159.0, 17928.282868685106, 17991.640167196845, 18094.909525452553, 18104.36634717805, 18106.45680629583, 15290.923545382202, 15418.46035388756, 15421.151836648867, 15426.6143346415, 15434.691306173814, 9037.819243615091, 9335.439873607605, 9527.38072025316, 9576.0, 9645.9035409645, 3662.304162209172, 3685.4103343464917, 3719.442083687414, 3720.795356255432, 3725.832337544817, 1056.508723443587, 1056.529844219315, 1071.908887744535, 1135.5401062569672, 1171.8769529199483, 34778.652213477544, 35114.47328290078, 35308.64691353105, 35422.34332425027, 35565.46651800225, 1160.4313886195703, 1160.9941950290363, 1206.4329765010432, 1213.5570516761318, 1250.5123002029216, 17714.645707085787, 18732.90633546851, 19307.324243651685, 19723.0, 20130.19479220844, 622297.0, 626969.0, 627822.0, 929870.0, 930856.0, 67049.32950670528, 67235.32764672389, 67691.0, 67737.66131169313, 67767.0, 1507.0806807847205, 1515.113658509784, 1516.5677781832126, 1535.1173075481643, 1547.597624617585, 35273.47089793604, 36677.0, 36692.63307366892, 37049.629503704615, 37112.628873712, 57649.423505766084, 58441.4155858436, 58441.70779146077, 58464.707676461345, 58481.415185847596, 542912.0, 548311.0, 548717.0, 549162.0, 551377.0, 36162.03370876306, 42098.57901420946, 45072.0, 49770.258040968394, 50327.24509132439, 50087.248691268935, 51327.0, 51474.48525514697, 51559.48440515547, 52407.47592524026, 44077.11845763003, 44719.776401117786, 44818.77590612026, 45587.316190257836, 45703.314450283935, 44010.11979760323, 44875.551244487135, 44984.77507612441, 45761.31358029565, 46219.76890115528, 44730.0, 45341.093178136915, 46038.30942535798, 46323.30515042209, 47673.52326476829, 37171.44242836306, 40302.19395612013, 40400.39399408952, 40676.3898541528, 40966.38550421806, 29679.85160074186, 29732.851335743184, 29983.0, 30143.0, 30680.693193067782], "value": 21521.096794568926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.2492918552148, 420.91160856220193, 430.18909355864184, 435.0776354129246, 435.57748983486016, 16020.67958640828, 20411.387658370237, 20935.37193884184, 20945.581088378225, 21544.13823447064, 19894.60210795783, 19901.3034543791, 19959.401217963485, 19962.60074798503, 20254.885981271025, 5776.249087618604, 5935.732892019859, 5966.194563733906, 6016.42843929826, 6041.0, 5904.232449781543, 5991.610545314563, 5992.9700351498395, 5995.250593675791, 5999.910001349983, 5785.884282314351, 5984.162217289583, 5984.5212383009475, 6005.87988240235, 6012.158297838306, 5808.215890854723, 5977.043673012297, 6007.128966299884, 6024.548158888079, 6069.848253793669, 5394.78420863169, 5424.701641409721, 5425.782968681248, 5430.5927055471, 5432.049391356536, 20487.28294509685, 23516.76483235163, 23988.760112398828, 24231.636525452133, 24414.755852441427, 2618.48939456806, 2821.4216085702383, 2962.896298629538, 3236.64396916339, 3358.630550639428, 14838.925805371013, 14939.70120597582, 15024.399024039025, 15090.39638414462, 15147.54557363281, 1979.9505012374807, 1992.232990298731, 2120.703101565782, 2124.743999242585, 2129.488922658551, 60771.39228607702, 65619.3438065618, 75929.86105208482, 83995.58002210011, 84277.57861210716, 10895.618653347095, 11392.373419461843, 11414.94292528532, 11550.711232219093, 11765.941170294094, 16539.25573349202, 16549.338026479047, 16558.6688266234, 16571.25429355682, 16575.751363729552, 8803.295736341077, 9061.365704400761, 9169.495677737656, 9176.31177661678, 9223.677171298972, 9041.367104302768, 9041.412308199946, 9043.276537876955, 9109.863352049724, 9266.444013359085, 7000.824979375557, 7038.6480675965995, 7092.59167615189, 7141.857162856818, 7180.7845764627145, 1969.2319995201883, 2034.9593008139755, 2063.6801295798873, 2080.708700781892, 2094.8533602647667, 822.6421506644629, 969.1665167955553, 1042.7549525861416, 1052.0636633396284, 1113.8551988241638, 20201.0, 20987.58024839525, 21313.786862131492, 21405.78594214069, 21424.892875535523, 1020.0360659177155, 1044.8693913260843, 1052.1687866585312, 1065.5950738719391, 1075.7525769073156, 11657.708557285967, 12176.224913941067, 12405.503779848705, 12627.116101872964, 12627.368631568568, 402357.0, 403159.0, 406596.0, 561493.0, 586074.0, 102209.48895255476, 106070.0, 109045.45477272721, 110427.0, 110918.0, 1057.2916146182076, 1059.9099076578423, 1066.834640630688, 1067.140951534007, 1067.7330667333167, 18969.620607587494, 19164.329248476235, 19269.0, 19326.323578674677, 19411.51471213231, 32726.50910236396, 40797.0, 43754.0, 44307.77846110749, 45438.54561454343, 63801.680991594745, 64119.358806411336, 64846.35153648592, 65153.67423162854, 65265.34734652783, 9205.585748641193, 9245.260379169516, 9401.10689484498, 9403.482808445327, 9533.380330278509, 10199.898001019896, 13461.125026873222, 13754.381052852446, 14134.78797818013, 14661.70676586441, 8827.823443530964, 11876.346800925687, 11880.227785193934, 12433.316167610868, 12601.306928118674, 7622.961885190539, 8033.799155021171, 12262.019038476721, 12375.876241237473, 12421.813672794735, 7374.520656157332, 12474.0, 12579.559715410283, 12865.614031579067, 13043.934780326037, 9134.771630709285, 9349.065093490868, 9469.715908522756, 9632.662856799978, 9731.318807683394, 11358.60244891425, 12380.380980951097, 12542.24746515212, 12565.0, 12662.873371266538], "value": 9886.112272058595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.48393788081, 1540.5378386484058, 1699.0, 1827.972580411295, 1828.076821205291, 9042.773930651738, 9719.95140024299, 9722.465264410472, 9723.70828875134, 9727.22182225422, 8308.376871734628, 8839.425437346585, 9015.413998090136, 9043.909560904389, 9566.904330956688, 2331.4404542909706, 2335.532893421314, 2337.5792357375667, 2341.906323747048, 2357.1278626907983, 2317.44381348476, 2319.5476882008015, 2363.6690863279155, 2364.3970787449175, 2364.586197415454, 2337.9649305260436, 2338.228384633067, 2339.7192336919593, 2360.9409764755846, 2376.6316220985686, 2230.832687548424, 2249.898754556041, 2257.7064981552376, 2258.446680563256, 2276.1578216059997, 2237.172246268891, 2247.1685476373846, 2248.2468373095035, 2249.741279752817, 2249.763774803655, 9347.579358928864, 9411.529423528797, 9419.24646028316, 9427.34008619397, 9432.245420366422, 5172.0, 5351.866203344908, 5357.4106848246665, 5360.303160589098, 5361.919571206435, 3253.6258330292085, 3335.6164041135094, 3435.2957643682985, 3438.7249020078584, 3446.6897979181745, 1090.432974853072, 1103.0844399148687, 1112.9499172537255, 1117.1844553475926, 1140.5950887435008, 13381.19712817234, 13456.0, 13489.392977316043, 13495.59513214605, 13499.527516536973, 4512.706674066175, 4518.6611004174165, 4717.198076327033, 4718.0, 4733.644976626767, 11107.278026928385, 11120.22158448917, 11156.219064665558, 11185.776284474265, 11196.66410007701, 5424.755885985141, 5464.562834973192, 5465.4261302563455, 5467.917981230285, 5483.150111732688, 4523.705959112647, 4885.975570122127, 4972.254161875675, 5042.974785126051, 5089.618278629117, 4106.299135412789, 4134.545200028055, 4206.978965105155, 4226.746395216297, 4245.80893859777, 1259.2387104444533, 1268.1630124118037, 1288.2335010668637, 1291.7029083310704, 1298.746744384836, 1021.8518314844306, 1089.8310761831929, 1090.5692251560747, 1097.5390336058874, 1100.2023532938556, 6529.118568993125, 6666.566673166229, 6712.328767123248, 6731.124953755981, 6875.621840798804, 450.80615335405435, 456.915470637933, 458.7453963050472, 460.99078018439445, 465.51353835242253, 4644.5123262056995, 4674.532546745366, 4714.66997310185, 4736.786844592001, 4823.589994850477, 11162.553497860157, 11641.53433862636, 11660.941695291469, 11672.066234701204, 11698.649040528628, 11288.548458061585, 11594.6521604352, 11664.825027624593, 11731.648050558497, 12088.09339299556, 8745.256653184551, 8934.195922366887, 8959.1936725695, 8972.057933916974, 8983.236424903957, 6699.43054840344, 6909.136357955255, 6939.653017349011, 7025.578465292099, 7065.682044307916, 11040.613578524713, 11254.943725281648, 11331.433428328715, 11332.37671928019, 11338.773224535298, 11104.111671066457, 11119.332840029625, 11289.887101129212, 11344.262622929484, 11458.885411145782, 7795.57124358143, 8345.83308333842, 8428.45215061019, 8581.613827377907, 8683.696070637498, 9486.762830929281, 9528.237740980843, 9552.23582113443, 9554.570044347882, 9555.378900371454, 8321.417500774887, 8341.20758527939, 8349.0, 8425.325973921948, 8436.198561136682, 7993.12075671664, 8042.83914321722, 8049.557274349967, 8076.798080047811, 8288.958555207186, 6850.075239842757, 6978.930210698031, 7053.329933656294, 7439.628018599156, 7509.249075092447, 5428.37573679032, 5476.342838859203, 5494.890102198014, 5522.66863988162, 5551.25058117149, 5495.422980586981, 5569.248151499496, 5655.575831812548, 5686.744096515749, 5797.652140871562], "value": 4808.399740609825, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.32405794787087, 356.839422259983, 358.2709186804852, 363.67632806801936, 365.1328095772542, 5549.667019978804, 5575.69333686647, 5614.3543492498375, 5633.887322253558, 5656.151577263413, 5172.612054095939, 5226.7909283628705, 5458.61789674723, 5503.174523821429, 5511.677197472608, 4223.788810559475, 4267.381229721688, 4270.295401258789, 4280.272353699878, 4293.677974151936, 4210.768407737573, 4261.680373971949, 4268.125034367963, 4280.9143817123795, 4286.485621725397, 4154.605312495308, 4233.449651545295, 4255.38295447731, 4282.400463935051, 4296.656267498608, 4245.61789438952, 4256.765877876715, 4281.293586558214, 4296.505901821283, 4310.525842157376, 3785.962140378616, 3797.8290976906105, 3799.449079883429, 3803.885883423474, 3815.8855234343014, 5236.81671141512, 5353.304070470853, 5359.651622644516, 5375.865603359908, 5380.65941315529, 4904.215325547895, 4912.38595175603, 4933.593925731149, 4935.605151587864, 4948.480409557017, 5697.77208911643, 5715.342735585419, 5730.512906402961, 5736.483716465496, 5737.598368114233, 4085.9387109193385, 4136.138737568106, 4168.91662166758, 4171.165766846643, 4194.832206711728, 5896.110905656241, 5904.409559044104, 5914.260717410324, 5933.376995415462, 5937.851553711105, 4272.230998420252, 4288.91422171561, 4289.67827412939, 4299.0, 4299.1831552005015, 4088.5911408858874, 4309.633681137076, 4339.631131353807, 4347.695661303678, 4358.934615980763, 4886.0, 4891.804327826918, 4898.53463920927, 4900.338454308694, 4907.895723462264, 5737.827865164052, 5742.329816635284, 5751.827445176651, 5785.392533783976, 5790.478856902899, 1439.79842822005, 1453.6438572549794, 1466.8019817324525, 1487.0, 1492.574616234368, 983.8475036369374, 1032.509557959978, 1050.837120246363, 1059.4808543813476, 1064.8615679961556, 5518.255035570227, 5518.33779946401, 5528.834134975958, 5606.327240731139, 5614.6631202128, 5749.913751293734, 5881.176635271066, 5884.499817515559, 5890.79238756054, 5895.140856684505, 5465.289512363367, 5527.972360138254, 5536.474034966672, 5541.722913854291, 5568.276124103922, 3091.7835751497178, 3103.8515749172952, 3121.4225368306934, 3127.9365015894705, 3131.1545882611567, 5698.344690360579, 5952.136940143655, 5955.3151387590115, 5969.552283578749, 5973.163757074013, 5840.685845684687, 5840.8539786504825, 5861.834609311009, 5916.497097746654, 5936.308152176609, 5787.2187254720075, 5968.940310596839, 5982.282126144891, 6003.0695242237525, 6015.3383127855905, 5790.710464476675, 5801.8549536261935, 5802.94197058024, 5808.796692115756, 5830.883382332415, 5654.519365853948, 5657.886842263214, 5666.660000399989, 5671.716414179357, 5724.427557244242, 5762.4525670061275, 5935.821925342247, 5941.613795103304, 6071.089336599369, 6075.908861366995, 4152.522459917148, 4160.958390416057, 4174.937375939303, 4184.81168347419, 4186.016286172686, 5878.94121058801, 5939.346671866005, 5943.197668314895, 5953.10703394477, 5966.791162309299, 5647.3010969862125, 5778.711064446677, 5793.493691640119, 5815.621984570989, 5820.370296317038, 5750.827475175752, 5757.0, 5758.568107391879, 5764.654120752768, 5767.163761254511, 5191.247269146028, 5454.4272851351625, 5517.282753242213, 5746.281714785652, 5761.8559536011935, 5782.710864456844, 5787.929233091892, 5815.563832712479, 5818.73815678304, 5830.387809279964, 5671.631343962629, 5673.858153546029, 5696.515796157373, 5705.546614955609, 5709.857253568694], "value": 4391.773164505231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.4717111854338, 813.6135335715536, 851.6040041380758, 865.8484765166103, 894.3292530602048, 14658.193799341045, 15789.842101578995, 15794.526164215093, 15815.762763558556, 15838.049717016987, 14688.706225875476, 14724.631884202874, 14738.410463581444, 14766.261686915665, 14770.778438323434, 701.484408959417, 834.6995081770558, 851.5997481183849, 868.5179725252482, 869.2871845087031, 864.7016779211193, 867.6702852915884, 867.8698195270729, 870.8214815962713, 875.2866413872673, 916.3081873185736, 917.1149840404008, 917.5962576466334, 918.1140199707281, 918.5820451694462, 912.2610685344891, 912.7124955638985, 913.4245425382001, 913.8720579118929, 914.0950459045564, 673.5049738442241, 680.1328306409367, 681.1009467502898, 681.6012632609929, 682.1234713393266, 14364.92817535916, 14371.640708982255, 14390.136591804525, 14395.568132956028, 14418.639534011734, 16300.0, 16325.59186020359, 16493.092879891603, 16870.409535666193, 16926.746098808406, 12840.165389249669, 12859.0, 13028.088033837636, 13184.0, 13443.798343024862, 3947.605239476058, 3959.703022273341, 4017.337139372, 4040.939385909214, 4083.183363327317, 85629.57185214096, 88079.55960220222, 88813.99999999936, 89358.5532072342, 91231.54384228103, 12365.505379784889, 12448.190867593577, 12526.749465010831, 12606.55877044299, 12704.74590508203, 24229.15197968098, 24407.26778196657, 24443.51112977766, 24544.386390340027, 24626.5074698505, 8965.686200982935, 9209.35534512591, 9213.894332680122, 9276.582553785096, 9277.489738064336, 4912.754362281871, 5000.149974504343, 5095.8980820383385, 5207.713575753294, 5236.214567814858, 3668.1013151777984, 3795.0891785971166, 3796.3925771876366, 3804.201117765245, 3805.7145714071557, 611.9357467465941, 621.1366200980641, 678.4097834883682, 716.2336300158884, 727.8362368467158, 696.5785699651638, 770.4221833624781, 799.6801279488135, 801.9318357939524, 804.2399932064143, 10249.641262555924, 10307.433091179908, 10677.733056673645, 11073.612423565297, 11119.44402779874, 620.5159975219336, 702.2626242445411, 754.6604028187338, 768.2432803688363, 770.7533589251386, 4829.154897892855, 4993.325901003391, 5131.948680513222, 5132.076226279306, 5144.536991670768, 120377.39811300888, 122581.77418225881, 123483.0, 124243.0, 129038.0, 67217.66391168012, 101367.49316253519, 103506.48246758718, 104540.47729761302, 108715.45642271738, 93933.53033234927, 95754.52122739481, 99814.50092749635, 101320.49339753254, 102463.4876825626, 9779.706608801747, 9783.217342612716, 9931.702048938543, 10126.645567405105, 10162.644307449204, 39398.212035759694, 51303.743481282356, 53380.46619533755, 54192.0, 62199.0, 49499.25751113808, 67273.66363168153, 75655.59549820161, 75834.62082689551, 77450.0, 13096.476140954255, 13099.93450032782, 13136.27750473733, 13448.663783405493, 14501.782473262698, 14880.404783808526, 15151.696966060837, 15263.160526171167, 15768.526944191693, 16096.195190240674, 14650.340734666752, 14763.203471821262, 15005.0, 15371.692566148391, 15454.84545154579, 13680.247586382844, 14403.207823569805, 15106.471273505374, 15334.616634584225, 16065.839341606434, 13308.800367994294, 13531.729365412833, 13765.105268157538, 14309.928450357682, 14421.134731916118, 10881.510332035235, 11066.889331106586, 11306.886931130912, 11435.885641143483, 11855.703607409883, 11129.165312601426, 11844.526218951145, 11844.76310473803, 11985.28088314704, 12220.205686630696], "value": 8021.656776309894, "unit": "score"}], "name": "dromaeo_css", "value": 7640.644586737289, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 120.0, 110.0, 116.0, 109.0, 108.0, 110.0, 111.0, 109.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 140.0, 164.0, 120.0, 140.0, 146.0, 121.0, 176.0, 115.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 167.0, 161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 188.0, 76.0, 75.0, 75.0, 75.0, 169.0, 75.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 166.0, 84.0, 84.0, 84.0, 151.0, 82.0, 83.0, 149.0], "value": 104.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 118.0, 118.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 101.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 50.0, 49.0, 49.0, 49.0, 51.0, 51.0, 49.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0, 61.0, 61.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 76.0, 73.0, 78.0, 72.0, 72.0, 72.0, 72.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 123.0, 131.0, 116.0, 126.0, 115.0, 126.0, 156.0, 117.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 155.0, 153.0, 154.0, 153.0, 161.0, 153.0, 153.0, 157.0, 152.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 63.0, 62.0, 65.0, 62.0, 62.0, 60.0, 60.0, 61.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1494.3, "alertThreshold": 2.0}]} 10:33:17 INFO - Return code: 0 10:33:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:33:17 INFO - # TBPL SUCCESS # 10:33:17 INFO - Running post-action listener: _resource_record_post_action 10:33:17 INFO - [mozharness: 2016-09-23 17:33:17.406932Z] Finished run-tests step (success) 10:33:17 INFO - Running post-run listener: _resource_record_post_run 10:33:17 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1736704; Write bytes: 288690176; Read time: 40; Write time: 270028 10:33:17 INFO - TinderboxPrint: CPU usage
12.9% 10:33:17 INFO - TinderboxPrint: I/O read bytes / time
1,736,704 / 40 10:33:17 INFO - TinderboxPrint: I/O write bytes / time
288,690,176 / 270,028 10:33:17 INFO - TinderboxPrint: CPU idle
8,253.7 (87.1%) 10:33:17 INFO - TinderboxPrint: CPU user
1,177.3 (12.4%) 10:33:17 INFO - TinderboxPrint: Swap in / out
0 / 0 10:33:17 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70103040; Read time: 0; Write time: 109924 10:33:17 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 218587136; Read time: 16; Write time: 160104 10:33:18 INFO - Running post-run listener: _upload_blobber_files 10:33:18 INFO - Blob upload gear active. 10:33:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:33:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:33:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:33:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:33:18 INFO - (blobuploader) - INFO - Open directory for files ... 10:33:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:33:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:22 INFO - (blobuploader) - INFO - Done attempting. 10:33:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:33:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:22 INFO - (blobuploader) - INFO - Done attempting. 10:33:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:33:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:33:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:23 INFO - (blobuploader) - INFO - Done attempting. 10:33:23 INFO - (blobuploader) - INFO - Iteration through files over. 10:33:23 INFO - Return code: 0 10:33:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:33:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:33:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/212f71118e8c52de6b4d4adcfcfdd58a71adeb6509d851ba44d84bb5a21170c400b220761c5a38dda591bfd3c25c68c0f0d37ac7f0d5603357efda67aca57832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9fa144f76cd228112b7dffbca2f57f75588a7d1c96154d60a3cb60f4babb991a569373b24310267c6cbfab02f6f957c0f0b38da14774b8902bf7754522f953f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/903813700e92684708729d04f382cd76bd7e67e49fcc7d4706a59ef15f3363354cc4b001f326d92024bb09aa2979454fb0e3090aa30ddf1a839d0a1049d36254"} 10:33:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:33:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:33:23 INFO - Contents: 10:33:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/212f71118e8c52de6b4d4adcfcfdd58a71adeb6509d851ba44d84bb5a21170c400b220761c5a38dda591bfd3c25c68c0f0d37ac7f0d5603357efda67aca57832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9fa144f76cd228112b7dffbca2f57f75588a7d1c96154d60a3cb60f4babb991a569373b24310267c6cbfab02f6f957c0f0b38da14774b8902bf7754522f953f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/903813700e92684708729d04f382cd76bd7e67e49fcc7d4706a59ef15f3363354cc4b001f326d92024bb09aa2979454fb0e3090aa30ddf1a839d0a1049d36254"} 10:33:23 INFO - Running post-run listener: copy_logs_to_upload_dir 10:33:23 INFO - Copying logs to upload dir... 10:33:23 INFO - mkdir: /builds/slave/test/build/upload/logs 10:33:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.001515 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-23 10:33:23.335133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 10:33:23.339168) ========= 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=fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017 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/212f71118e8c52de6b4d4adcfcfdd58a71adeb6509d851ba44d84bb5a21170c400b220761c5a38dda591bfd3c25c68c0f0d37ac7f0d5603357efda67aca57832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9fa144f76cd228112b7dffbca2f57f75588a7d1c96154d60a3cb60f4babb991a569373b24310267c6cbfab02f6f957c0f0b38da14774b8902bf7754522f953f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/903813700e92684708729d04f382cd76bd7e67e49fcc7d4706a59ef15f3363354cc4b001f326d92024bb09aa2979454fb0e3090aa30ddf1a839d0a1049d36254"} build_url:https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/JPFHz9BDRDK5PFEmjlcpKQ/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/212f71118e8c52de6b4d4adcfcfdd58a71adeb6509d851ba44d84bb5a21170c400b220761c5a38dda591bfd3c25c68c0f0d37ac7f0d5603357efda67aca57832", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9fa144f76cd228112b7dffbca2f57f75588a7d1c96154d60a3cb60f4babb991a569373b24310267c6cbfab02f6f957c0f0b38da14774b8902bf7754522f953f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/903813700e92684708729d04f382cd76bd7e67e49fcc7d4706a59ef15f3363354cc4b001f326d92024bb09aa2979454fb0e3090aa30ddf1a839d0a1049d36254"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 10:33:23.364946) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 10:33:23.365301) ========= 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=fa44defc139c4ba8574fe040000001db-1474650325.118807-818846017 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 10:33:23.447503) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 10:33:23.447851) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 10:33:23.448169) ========= ========= Total master_lag: 0.15 =========