builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-011 starttime: 1474062370.33 results: success (0) buildid: 20160916120518 builduid: c21f0eb4260d4202aa3f049fe2a13c35 revision: 8c37b5ac91e8671700cec6faed487e19f01e9e3c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.328436) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.328903) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.329176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.348043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.348301) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 14:46:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-09-16 14:46:10 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119988 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.481959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.482272) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019907 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.531441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:10.531799) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8c37b5ac91e8671700cec6faed487e19f01e9e3c --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-aurora --rev 8c37b5ac91e8671700cec6faed487e19f01e9e3c --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=5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 14:46:10,887 truncating revision to first 12 chars 2016-09-16 14:46:10,887 Setting DEBUG logging. 2016-09-16 14:46:10,887 attempt 1/10 2016-09-16 14:46:10,887 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8c37b5ac91e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 14:46:11,294 unpacking tar archive at: mozilla-aurora-8c37b5ac91e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.778065 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:11.323544) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:11.323863) ========= script_repo_revision: 8c37b5ac91e8671700cec6faed487e19f01e9e3c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:11.324257) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:11.324523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 14:46:11.333833) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-09-16 14:46:11.334122) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:46:11 INFO - MultiFileLogger online at 20160916 14:46:11 in /builds/slave/test 14:46:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:46:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:46:11 INFO - {'append_to_log': False, 14:46:11 INFO - 'base_work_dir': '/builds/slave/test', 14:46:11 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:46:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:46:11 INFO - 'branch': 'Mozilla-Aurora', 14:46:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:46:11 INFO - 'config_files': ('talos/linux_config.py',), 14:46:11 INFO - 'default_actions': ('clobber', 14:46:11 INFO - 'read-buildbot-config', 14:46:11 INFO - 'download-and-extract', 14:46:11 INFO - 'populate-webroot', 14:46:11 INFO - 'create-virtualenv', 14:46:11 INFO - 'install', 14:46:11 INFO - 'run-tests'), 14:46:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:46:11 INFO - 'download_minidump_stackwalk': True, 14:46:11 INFO - 'download_symbols': 'ondemand', 14:46:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:46:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:46:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:46:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:46:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:46:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:46:11 INFO - 'installer_path': 'installer.exe', 14:46:11 INFO - 'log_level': 'info', 14:46:11 INFO - 'log_name': 'talos', 14:46:11 INFO - 'log_to_console': True, 14:46:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:46:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:46:11 INFO - 'opt_config_files': (), 14:46:11 INFO - 'pip_index': False, 14:46:11 INFO - 'sps_profile': False, 14:46:11 INFO - 'sps_profile_interval': 0, 14:46:11 INFO - 'suite': 'dromaeojs-e10s', 14:46:11 INFO - 'system_bits': '32', 14:46:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:46:11 INFO - 'title': 'talos-linux64-ix-011', 14:46:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:46:11 INFO - 'use_talos_json': True, 14:46:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:46:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:46:11 INFO - 'work_dir': 'build'} 14:46:11 INFO - [mozharness: 2016-09-16 21:46:11.780811Z] Running clobber step. 14:46:11 INFO - Running pre-action listener: _resource_record_pre_action 14:46:11 INFO - Running main action method: clobber 14:46:11 INFO - rmtree: /builds/slave/test/build 14:46:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:46:12 INFO - Running post-action listener: _resource_record_post_action 14:46:12 INFO - [mozharness: 2016-09-16 21:46:12.239747Z] Finished clobber step (success) 14:46:12 INFO - [mozharness: 2016-09-16 21:46:12.239847Z] Running read-buildbot-config step. 14:46:12 INFO - Running pre-action listener: _resource_record_pre_action 14:46:12 INFO - Running main action method: read_buildbot_config 14:46:12 INFO - Using buildbot properties: 14:46:12 INFO - { 14:46:12 INFO - "project": "", 14:46:12 INFO - "product": "firefox", 14:46:12 INFO - "script_repo_revision": "production", 14:46:12 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 14:46:12 INFO - "repository": "", 14:46:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 14:46:12 INFO - "buildid": "20160916120518", 14:46:12 INFO - "pgo_build": "True", 14:46:12 INFO - "basedir": "/builds/slave/test", 14:46:12 INFO - "buildnumber": 560, 14:46:12 INFO - "slavename": "talos-linux64-ix-011", 14:46:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:46:12 INFO - "platform": "ubuntu64_hw", 14:46:12 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:46:12 INFO - "branch": "mozilla-aurora", 14:46:12 INFO - "repo_path": "releases/mozilla-aurora", 14:46:12 INFO - "revision": "8c37b5ac91e8671700cec6faed487e19f01e9e3c", 14:46:12 INFO - "stage_platform": "linux64-pgo", 14:46:12 INFO - "builduid": "c21f0eb4260d4202aa3f049fe2a13c35", 14:46:12 INFO - "slavebuilddir": "test" 14:46:12 INFO - } 14:46:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:46:12 INFO - Running post-action listener: _resource_record_post_action 14:46:12 INFO - [mozharness: 2016-09-16 21:46:12.242329Z] Finished read-buildbot-config step (success) 14:46:12 INFO - [mozharness: 2016-09-16 21:46:12.242419Z] Running download-and-extract step. 14:46:12 INFO - Running pre-action listener: _resource_record_pre_action 14:46:12 INFO - Running main action method: download_and_extract 14:46:12 INFO - mkdir: /builds/slave/test/build/tests 14:46: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')]} 14:46:12 INFO - https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:46:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:46:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:46:12 INFO - retry: Failed, sleeping 30 seconds before retrying 14:46:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 14:46:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:46:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:46:42 INFO - retry: Failed, sleeping 60 seconds before retrying 14:47:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 14:47:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:47:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:47:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 14:47:42 INFO - Caught exception: HTTP Error 404: Not Found 14:47:42 INFO - Caught exception: HTTP Error 404: Not Found 14:47:42 INFO - Caught exception: HTTP Error 404: Not Found 14:47:42 INFO - trying https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:47:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:47:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:47:42 INFO - Downloaded 1361 bytes. 14:47:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:47:42 INFO - Using the following test package requirements: 14:47:42 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:47:42 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:47:42 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:47:42 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'jsshell-linux-x86_64.zip'], 14:47:42 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:47:42 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:47:42 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:47:42 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:47:42 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:47:42 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:47:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:47:42 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:47:42 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')]} 14:47:42 INFO - https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:47:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:47:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:47:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:47:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:47:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:47:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:47:42 INFO - retry: Failed, sleeping 30 seconds before retrying 14:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:48:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:48:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:48:12 INFO - retry: Failed, sleeping 60 seconds before retrying 14:49:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:49:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:49:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:49:13 INFO - Caught exception: HTTP Error 404: Not Found 14:49:13 INFO - Caught exception: HTTP Error 404: Not Found 14:49:13 INFO - Caught exception: HTTP Error 404: Not Found 14:49:13 INFO - trying https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:49:13 INFO - Downloaded 21840276 bytes. 14:49:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:49:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:49:15 INFO - Return code: 0 14:49:15 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:49:15 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')]} 14:49:15 INFO - https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:49:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:49:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:49:15 INFO - retry: Failed, sleeping 30 seconds before retrying 14:49:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:49:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:49:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:49:45 INFO - retry: Failed, sleeping 60 seconds before retrying 14:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:50:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:50:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:50:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:50:45 INFO - Caught exception: HTTP Error 404: Not Found 14:50:45 INFO - Caught exception: HTTP Error 404: Not Found 14:50:45 INFO - Caught exception: HTTP Error 404: Not Found 14:50:45 INFO - trying https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:50:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:50:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:50:45 INFO - Downloaded 21840276 bytes. 14:50:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:50:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:50:47 INFO - Return code: 0 14:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:50:47 INFO - https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:50:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:50:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:50:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:50:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:50:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:50:47 INFO - retry: Failed, sleeping 30 seconds before retrying 14:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:51:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:51:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:51:17 INFO - retry: Failed, sleeping 60 seconds before retrying 14:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:52:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:52:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:52:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 14:52:17 INFO - Caught exception: HTTP Error 404: Not Found 14:52:17 INFO - Caught exception: HTTP Error 404: Not Found 14:52:17 INFO - Caught exception: HTTP Error 404: Not Found 14:52:17 INFO - trying https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:52:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:52:18 INFO - Downloaded 14413740 bytes. 14:52:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:52:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:52:18 INFO - Return code: 0 14:52:18 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')]} 14:52:18 INFO - https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:52:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:52:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:52:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:52:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:52:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:52:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:52:18 INFO - retry: Failed, sleeping 30 seconds before retrying 14:52:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:52:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:52:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:52:48 INFO - retry: Failed, sleeping 60 seconds before retrying 14:53:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:53:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:53:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 14:53:48 INFO - Caught exception: HTTP Error 404: Not Found 14:53:48 INFO - Caught exception: HTTP Error 404: Not Found 14:53:48 INFO - Caught exception: HTTP Error 404: Not Found 14:53:48 INFO - trying https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:53:50 INFO - Downloaded 63893938 bytes. 14:53:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:50 INFO - mkdir: /builds/slave/test/properties 14:53:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:53:50 INFO - Writing to file /builds/slave/test/properties/build_url 14:53:50 INFO - Contents: 14:53:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:53:50 INFO - Running post-action listener: _resource_record_post_action 14:53:50 INFO - Running post-action listener: set_extra_try_arguments 14:53:50 INFO - [mozharness: 2016-09-16 21:53:50.768452Z] Finished download-and-extract step (success) 14:53:50 INFO - [mozharness: 2016-09-16 21:53:50.768687Z] Running populate-webroot step. 14:53:50 INFO - Running pre-action listener: _resource_record_pre_action 14:53:50 INFO - Running main action method: populate_webroot 14:53:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:53:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:53:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:53:50 INFO - '--tppagecycles', 14:53:50 INFO - '7'], 14:53:50 INFO - 'tests': ['tsvgm']}}, 14:53:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:53:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:53:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:53:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:53:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:53:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:53:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['damp', 'tps']}, 14:53:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'tests': ['damp', 'tps']}, 14:53:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['dromaeo_dom']}, 14:53:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:53:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['basic_compositor_video']}, 14:53:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:53:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['a11yr', 14:53:50 INFO - 'ts_paint', 14:53:50 INFO - 'tpaint', 14:53:50 INFO - 'sessionrestore', 14:53:50 INFO - 'sessionrestore_no_auto_restore', 14:53:50 INFO - 'tabpaint']}, 14:53:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:53:50 INFO - 'ts_paint', 14:53:50 INFO - 'tpaint', 14:53:50 INFO - 'sessionrestore', 14:53:50 INFO - 'sessionrestore_no_auto_restore', 14:53:50 INFO - 'tabpaint']}, 14:53:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:53:50 INFO - 'ts_paint', 14:53:50 INFO - 'tpaint', 14:53:50 INFO - 'sessionrestore', 14:53:50 INFO - 'sessionrestore_no_auto_restore', 14:53:50 INFO - 'tabpaint']}, 14:53:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:53:50 INFO - 'ts_paint', 14:53:50 INFO - 'tpaint', 14:53:50 INFO - 'sessionrestore', 14:53:50 INFO - 'sessionrestore_no_auto_restore', 14:53:50 INFO - 'tabpaint']}, 14:53:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['a11yr', 14:53:50 INFO - 'ts_paint', 14:53:50 INFO - 'tpaint', 14:53:50 INFO - 'sessionrestore', 14:53:50 INFO - 'sessionrestore_no_auto_restore', 14:53:50 INFO - 'tabpaint']}, 14:53:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['a11yr', 14:53:50 INFO - 'ts_paint', 14:53:50 INFO - 'tpaint', 14:53:50 INFO - 'sessionrestore', 14:53:50 INFO - 'sessionrestore_no_auto_restore', 14:53:50 INFO - 'tabpaint']}, 14:53:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['tsvgx', 14:53:50 INFO - 'tsvgr_opacity', 14:53:50 INFO - 'tart', 14:53:50 INFO - 'tscrollx', 14:53:50 INFO - 'cart']}, 14:53:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:53:50 INFO - 'tsvgr_opacity', 14:53:50 INFO - 'tart', 14:53:50 INFO - 'tscrollx', 14:53:50 INFO - 'cart']}, 14:53:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'talos_options': ['--disable-e10s'], 14:53:50 INFO - 'tests': ['tp5o']}, 14:53:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'tests': ['tp5o']}, 14:53:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'talos_options': ['--disable-e10s', 14:53:50 INFO - '--xperf_path', 14:53:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:53:50 INFO - 'tests': ['tp5n']}, 14:53:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:50 INFO - 'talos_options': ['--xperf_path', 14:53:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:53:50 INFO - 'tests': ['tp5n']}}, 14:53:50 INFO - 'talos.zip': {'path': '', 14:53:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:53:50 INFO - Running post-action listener: _resource_record_post_action 14:53:50 INFO - [mozharness: 2016-09-16 21:53:50.786920Z] Finished populate-webroot step (success) 14:53:50 INFO - [mozharness: 2016-09-16 21:53:50.787008Z] Running create-virtualenv step. 14:53:50 INFO - Running pre-action listener: _resource_record_pre_action 14:53:50 INFO - Running main action method: create_virtualenv 14:53:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:53:50 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 14:53:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:53:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:53:50 INFO - Using real prefix '/usr' 14:53:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:53:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:53:53 INFO - Installing pip.................done. 14:53:53 INFO - Return code: 0 14:53:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:53 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:53:53 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 14:53:53 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 14:53:53 INFO - Using env: {'DISPLAY': ':0', 14:53:53 INFO - 'HOME': '/home/cltbld', 14:53:53 INFO - 'LANG': 'en_US.UTF-8', 14:53:53 INFO - 'LANGUAGE': 'en_US:en', 14:53:53 INFO - 'LOGNAME': 'cltbld', 14:53:53 INFO - 'MAIL': '/var/mail/cltbld', 14:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:53 INFO - 'MOZ_NO_REMOTE': '1', 14:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:53 INFO - 'NO_EM_RESTART': '1', 14:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:53 INFO - 'PWD': '/builds/slave/test', 14:53:53 INFO - 'SHELL': '/bin/bash', 14:53:53 INFO - 'SHLVL': '1', 14:53:53 INFO - 'TERM': 'linux', 14:53:53 INFO - 'TMOUT': '86400', 14:53:53 INFO - 'USER': 'cltbld', 14:53:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:53 INFO - '_': '/tools/buildbot/bin/python'} 14:53:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:53 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 14:53:53 INFO - Cleaning up... 14:53:53 INFO - Return code: 0 14:53:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:53 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:53:53 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 14:53:53 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 14:53:53 INFO - Using env: {'DISPLAY': ':0', 14:53:53 INFO - 'HOME': '/home/cltbld', 14:53:53 INFO - 'LANG': 'en_US.UTF-8', 14:53:53 INFO - 'LANGUAGE': 'en_US:en', 14:53:53 INFO - 'LOGNAME': 'cltbld', 14:53:53 INFO - 'MAIL': '/var/mail/cltbld', 14:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:53 INFO - 'MOZ_NO_REMOTE': '1', 14:53:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:53 INFO - 'NO_EM_RESTART': '1', 14:53:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:53 INFO - 'PWD': '/builds/slave/test', 14:53:53 INFO - 'SHELL': '/bin/bash', 14:53:53 INFO - 'SHLVL': '1', 14:53:53 INFO - 'TERM': 'linux', 14:53:53 INFO - 'TMOUT': '86400', 14:53:53 INFO - 'USER': 'cltbld', 14:53:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:53 INFO - '_': '/tools/buildbot/bin/python'} 14:53:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:53 INFO - Downloading/unpacking psutil>=3.1.1 14:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:53:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:55 INFO - Installing collected packages: psutil 14:53:55 INFO - Running setup.py install for psutil 14:53:55 INFO - building 'psutil._psutil_linux' extension 14:53:55 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 14:53:56 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 14:53:56 INFO - building 'psutil._psutil_posix' extension 14:53:56 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 14:53:56 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 14:53:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:56 INFO - Successfully installed psutil 14:53:56 INFO - Cleaning up... 14:53:56 INFO - Return code: 0 14:53:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:53:56 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')]} 14:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:56 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')]} 14:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:56 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:53:56 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 14:53:56 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 14:53:56 INFO - Using env: {'DISPLAY': ':0', 14:53:56 INFO - 'HOME': '/home/cltbld', 14:53:56 INFO - 'LANG': 'en_US.UTF-8', 14:53:56 INFO - 'LANGUAGE': 'en_US:en', 14:53:56 INFO - 'LOGNAME': 'cltbld', 14:53:56 INFO - 'MAIL': '/var/mail/cltbld', 14:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:56 INFO - 'MOZ_NO_REMOTE': '1', 14:53:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:56 INFO - 'NO_EM_RESTART': '1', 14:53:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:56 INFO - 'PWD': '/builds/slave/test', 14:53:56 INFO - 'SHELL': '/bin/bash', 14:53:56 INFO - 'SHLVL': '1', 14:53:56 INFO - 'TERM': 'linux', 14:53:56 INFO - 'TMOUT': '86400', 14:53:56 INFO - 'USER': 'cltbld', 14:53:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:56 INFO - '_': '/tools/buildbot/bin/python'} 14:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:53:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:53:58 INFO - Installing collected packages: mozsystemmonitor 14:53:58 INFO - Running setup.py install for mozsystemmonitor 14:53:58 INFO - Successfully installed mozsystemmonitor 14:53:58 INFO - Cleaning up... 14:53:58 INFO - Return code: 0 14:53:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:58 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:53:58 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 14:53:58 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 14:53:58 INFO - Using env: {'DISPLAY': ':0', 14:53:58 INFO - 'HOME': '/home/cltbld', 14:53:58 INFO - 'LANG': 'en_US.UTF-8', 14:53:58 INFO - 'LANGUAGE': 'en_US:en', 14:53:58 INFO - 'LOGNAME': 'cltbld', 14:53:58 INFO - 'MAIL': '/var/mail/cltbld', 14:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:58 INFO - 'MOZ_NO_REMOTE': '1', 14:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:58 INFO - 'NO_EM_RESTART': '1', 14:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:58 INFO - 'PWD': '/builds/slave/test', 14:53:58 INFO - 'SHELL': '/bin/bash', 14:53:58 INFO - 'SHLVL': '1', 14:53:58 INFO - 'TERM': 'linux', 14:53:58 INFO - 'TMOUT': '86400', 14:53:58 INFO - 'USER': 'cltbld', 14:53:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:58 INFO - '_': '/tools/buildbot/bin/python'} 14:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:58 INFO - Downloading/unpacking jsonschema==2.5.1 14:53:58 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 14:53:58 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 14:53:58 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 14:53:58 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 14:53:58 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 14:53:58 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 14:54:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:54:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:54:00 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 14:54:00 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 14:54:00 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 14:54:00 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 14:54:00 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 14:54:00 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 14:54:01 INFO - Downloading functools32-3.2.3-2.tar.gz 14:54:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:54:01 INFO - warning: no files found matching '*.txt' 14:54:01 INFO - no previously-included directories found matching 'build' 14:54:01 INFO - no previously-included directories found matching 'dist' 14:54:01 INFO - no previously-included directories found matching '.git*' 14:54:01 INFO - Installing collected packages: jsonschema, functools32 14:54:01 INFO - Running setup.py install for functools32 14:54:01 INFO - warning: no files found matching '*.txt' 14:54:01 INFO - no previously-included directories found matching 'build' 14:54:01 INFO - no previously-included directories found matching 'dist' 14:54:01 INFO - no previously-included directories found matching '.git*' 14:54:01 INFO - Successfully installed jsonschema functools32 14:54:01 INFO - Cleaning up... 14:54:01 INFO - Return code: 0 14:54:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:01 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:54:01 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 14:54:01 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 14:54:01 INFO - Using env: {'DISPLAY': ':0', 14:54:01 INFO - 'HOME': '/home/cltbld', 14:54:01 INFO - 'LANG': 'en_US.UTF-8', 14:54:01 INFO - 'LANGUAGE': 'en_US:en', 14:54:01 INFO - 'LOGNAME': 'cltbld', 14:54:01 INFO - 'MAIL': '/var/mail/cltbld', 14:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:01 INFO - 'MOZ_NO_REMOTE': '1', 14:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:01 INFO - 'NO_EM_RESTART': '1', 14:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:01 INFO - 'PWD': '/builds/slave/test', 14:54:01 INFO - 'SHELL': '/bin/bash', 14:54:01 INFO - 'SHLVL': '1', 14:54:01 INFO - 'TERM': 'linux', 14:54:01 INFO - 'TMOUT': '86400', 14:54:01 INFO - 'USER': 'cltbld', 14:54:01 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:01 INFO - '_': '/tools/buildbot/bin/python'} 14:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:54:01 INFO - Cleaning up... 14:54:01 INFO - Return code: 0 14:54:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:01 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:54:01 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 14:54:01 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 14:54:01 INFO - Using env: {'DISPLAY': ':0', 14:54:01 INFO - 'HOME': '/home/cltbld', 14:54:01 INFO - 'LANG': 'en_US.UTF-8', 14:54:01 INFO - 'LANGUAGE': 'en_US:en', 14:54:01 INFO - 'LOGNAME': 'cltbld', 14:54:01 INFO - 'MAIL': '/var/mail/cltbld', 14:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:01 INFO - 'MOZ_NO_REMOTE': '1', 14:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:01 INFO - 'NO_EM_RESTART': '1', 14:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:01 INFO - 'PWD': '/builds/slave/test', 14:54:01 INFO - 'SHELL': '/bin/bash', 14:54:01 INFO - 'SHLVL': '1', 14:54:01 INFO - 'TERM': 'linux', 14:54:01 INFO - 'TMOUT': '86400', 14:54:01 INFO - 'USER': 'cltbld', 14:54:01 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:01 INFO - '_': '/tools/buildbot/bin/python'} 14:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:01 INFO - Downloading/unpacking blobuploader==1.2.4 14:54:01 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 14:54:01 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 14:54:01 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 14:54:01 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 14:54:01 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 14:54:01 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 14:54:03 INFO - Downloading blobuploader-1.2.4.tar.gz 14:54:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:54:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:54:03 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 14:54:03 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 14:54:03 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 14:54:03 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 14:54:03 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 14:54:03 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 14:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:54:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:54:04 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 14:54:04 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 14:54:04 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 14:54:04 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 14:54:04 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 14:54:04 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 14:54:04 INFO - Downloading docopt-0.6.1.tar.gz 14:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:54:04 INFO - Installing collected packages: blobuploader, requests, docopt 14:54:04 INFO - Running setup.py install for blobuploader 14:54:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:54:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:54:04 INFO - Running setup.py install for requests 14:54:04 INFO - Running setup.py install for docopt 14:54:05 INFO - Successfully installed blobuploader requests docopt 14:54:05 INFO - Cleaning up... 14:54:05 INFO - Return code: 0 14:54:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:05 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:54:05 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 14:54:05 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 14:54:05 INFO - Using env: {'DISPLAY': ':0', 14:54:05 INFO - 'HOME': '/home/cltbld', 14:54:05 INFO - 'LANG': 'en_US.UTF-8', 14:54:05 INFO - 'LANGUAGE': 'en_US:en', 14:54:05 INFO - 'LOGNAME': 'cltbld', 14:54:05 INFO - 'MAIL': '/var/mail/cltbld', 14:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:05 INFO - 'MOZ_NO_REMOTE': '1', 14:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:05 INFO - 'NO_EM_RESTART': '1', 14:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:05 INFO - 'PWD': '/builds/slave/test', 14:54:05 INFO - 'SHELL': '/bin/bash', 14:54:05 INFO - 'SHLVL': '1', 14:54:05 INFO - 'TERM': 'linux', 14:54:05 INFO - 'TMOUT': '86400', 14:54:05 INFO - 'USER': 'cltbld', 14:54:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:05 INFO - '_': '/tools/buildbot/bin/python'} 14:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:05 INFO - Running setup.py (path:/tmp/pip-NpS3YQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:05 INFO - Running setup.py (path:/tmp/pip-PqR5yL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:05 INFO - Running setup.py (path:/tmp/pip-Q4wn1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:05 INFO - Running setup.py (path:/tmp/pip-Cdubud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:05 INFO - Running setup.py (path:/tmp/pip-gbir_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:05 INFO - Running setup.py (path:/tmp/pip-Myek_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:05 INFO - Running setup.py (path:/tmp/pip-NGrqrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:05 INFO - Running setup.py (path:/tmp/pip-uGXUZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:05 INFO - Running setup.py (path:/tmp/pip-gS3R2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:05 INFO - Running setup.py (path:/tmp/pip-WjJNU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:05 INFO - Running setup.py (path:/tmp/pip-k9kKT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:06 INFO - Running setup.py (path:/tmp/pip-J_9INi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:06 INFO - Running setup.py (path:/tmp/pip-LiRNFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:06 INFO - Running setup.py (path:/tmp/pip-j_hJSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:06 INFO - Running setup.py (path:/tmp/pip-mZMze_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:06 INFO - Running setup.py (path:/tmp/pip-3o8mpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:06 INFO - Running setup.py (path:/tmp/pip-yryUGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:54:06 INFO - Running setup.py install for manifestparser 14:54:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:54:06 INFO - Running setup.py install for mozcrash 14:54:06 INFO - Running setup.py install for mozdebug 14:54:06 INFO - Running setup.py install for mozdevice 14:54:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:54:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:54:06 INFO - Running setup.py install for mozfile 14:54:07 INFO - Running setup.py install for mozhttpd 14:54:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for mozinfo 14:54:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for mozInstall 14:54:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for mozleak 14:54:07 INFO - Running setup.py install for mozlog 14:54:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for moznetwork 14:54:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for mozprocess 14:54:07 INFO - Running setup.py install for mozprofile 14:54:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for mozrunner 14:54:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:54:07 INFO - Running setup.py install for mozscreenshot 14:54:08 INFO - Running setup.py install for moztest 14:54:08 INFO - Running setup.py install for mozversion 14:54:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:54:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:54:08 INFO - Cleaning up... 14:54:08 INFO - Return code: 0 14:54:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:08 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:54:08 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 14:54:08 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 14:54:08 INFO - Using env: {'DISPLAY': ':0', 14:54:08 INFO - 'HOME': '/home/cltbld', 14:54:08 INFO - 'LANG': 'en_US.UTF-8', 14:54:08 INFO - 'LANGUAGE': 'en_US:en', 14:54:08 INFO - 'LOGNAME': 'cltbld', 14:54:08 INFO - 'MAIL': '/var/mail/cltbld', 14:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:08 INFO - 'MOZ_NO_REMOTE': '1', 14:54:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:08 INFO - 'NO_EM_RESTART': '1', 14:54:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:08 INFO - 'PWD': '/builds/slave/test', 14:54:08 INFO - 'SHELL': '/bin/bash', 14:54:08 INFO - 'SHLVL': '1', 14:54:08 INFO - 'TERM': 'linux', 14:54:08 INFO - 'TMOUT': '86400', 14:54:08 INFO - 'USER': 'cltbld', 14:54:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:08 INFO - '_': '/tools/buildbot/bin/python'} 14:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:54:08 INFO - Running setup.py (path:/tmp/pip-sA_K9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:54:08 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)) 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:54:08 INFO - Running setup.py (path:/tmp/pip-udsDTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:54:08 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)) 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:54:08 INFO - Running setup.py (path:/tmp/pip-eL2C7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:54:08 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)) 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:54:08 INFO - Running setup.py (path:/tmp/pip-TxVrZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:54:08 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)) 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:54:08 INFO - Running setup.py (path:/tmp/pip-5Hnbc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:54:08 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)) 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:54:08 INFO - Running setup.py (path:/tmp/pip-8B4P7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:54:08 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)) 14:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:54:08 INFO - Running setup.py (path:/tmp/pip-NJ5RKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:54:09 INFO - Running setup.py (path:/tmp/pip-y8UFav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:54:09 INFO - Running setup.py (path:/tmp/pip-D1davD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:54:09 INFO - Running setup.py (path:/tmp/pip-8NSovh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:54:09 INFO - Running setup.py (path:/tmp/pip-6MwKFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:54:09 INFO - Running setup.py (path:/tmp/pip-nGCJwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:54:09 INFO - Running setup.py (path:/tmp/pip-KyxorS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:54:09 INFO - Running setup.py (path:/tmp/pip-KqwcaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:09 INFO - Running setup.py (path:/tmp/pip-G4BncY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:54:09 INFO - Running setup.py (path:/tmp/pip-MMPyNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:54:09 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)) 14:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:54:09 INFO - Running setup.py (path:/tmp/pip-4iPPcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:54:09 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)) 14:54:09 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)) 14:54:09 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)) 14:54:09 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)) 14:54:09 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)) 14:54:09 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)) 14:54:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:54:09 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 14:54:09 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 14:54:09 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 14:54:09 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 14:54:09 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 14:54:09 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 14:54:11 INFO - Downloading blessings-1.6.tar.gz 14:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:54:11 INFO - Installing collected packages: blessings 14:54:11 INFO - Running setup.py install for blessings 14:54:11 INFO - Successfully installed blessings 14:54:11 INFO - Cleaning up... 14:54:11 INFO - Return code: 0 14:54:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:54:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:12 INFO - Reading from file tmpfile_stdout 14:54:12 INFO - Current package versions: 14:54:12 INFO - argparse == 1.2.1 14:54:12 INFO - blessings == 1.6 14:54:12 INFO - blobuploader == 1.2.4 14:54:12 INFO - docopt == 0.6.1 14:54:12 INFO - functools32 == 3.2.3-2 14:54:12 INFO - jsonschema == 2.5.1 14:54:12 INFO - manifestparser == 1.1 14:54:12 INFO - mozInstall == 1.12 14:54:12 INFO - mozcrash == 0.17 14:54:12 INFO - mozdebug == 0.1 14:54:12 INFO - mozdevice == 0.48 14:54:12 INFO - mozfile == 1.2 14:54:12 INFO - mozhttpd == 0.7 14:54:12 INFO - mozinfo == 0.9 14:54:12 INFO - mozleak == 0.1 14:54:12 INFO - mozlog == 3.3 14:54:12 INFO - moznetwork == 0.27 14:54:12 INFO - mozprocess == 0.23 14:54:12 INFO - mozprofile == 0.28 14:54:12 INFO - mozrunner == 6.12 14:54:12 INFO - mozscreenshot == 0.1 14:54:12 INFO - mozsystemmonitor == 0.3 14:54:12 INFO - moztest == 0.7 14:54:12 INFO - mozversion == 1.4 14:54:12 INFO - psutil == 3.1.1 14:54:12 INFO - requests == 1.2.3 14:54:12 INFO - wsgiref == 0.1.2 14:54:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54:12 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 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:54:12 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 14:54:12 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 14:54:12 INFO - Using env: {'DISPLAY': ':0', 14:54:12 INFO - 'HOME': '/home/cltbld', 14:54:12 INFO - 'LANG': 'en_US.UTF-8', 14:54:12 INFO - 'LANGUAGE': 'en_US:en', 14:54:12 INFO - 'LOGNAME': 'cltbld', 14:54:12 INFO - 'MAIL': '/var/mail/cltbld', 14:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:12 INFO - 'MOZ_NO_REMOTE': '1', 14:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:12 INFO - 'NO_EM_RESTART': '1', 14:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:12 INFO - 'PWD': '/builds/slave/test', 14:54:12 INFO - 'SHELL': '/bin/bash', 14:54:12 INFO - 'SHLVL': '1', 14:54:12 INFO - 'TERM': 'linux', 14:54:12 INFO - 'TMOUT': '86400', 14:54:12 INFO - 'USER': 'cltbld', 14:54:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:12 INFO - '_': '/tools/buildbot/bin/python'} 14:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 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)) 14:54:12 INFO - Cleaning up... 14:54:12 INFO - Return code: 0 14:54:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:54: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')]} 14:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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')]} 14:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:54: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x232c400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, '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': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', '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 14:54: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', 'jsonschema'] in /builds/slave/test/build 14:54: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 jsonschema 14:54:12 INFO - Using env: {'DISPLAY': ':0', 14:54:12 INFO - 'HOME': '/home/cltbld', 14:54:12 INFO - 'LANG': 'en_US.UTF-8', 14:54:12 INFO - 'LANGUAGE': 'en_US:en', 14:54:12 INFO - 'LOGNAME': 'cltbld', 14:54:12 INFO - 'MAIL': '/var/mail/cltbld', 14:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:12 INFO - 'MOZ_NO_REMOTE': '1', 14:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:12 INFO - 'NO_EM_RESTART': '1', 14:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:12 INFO - 'PWD': '/builds/slave/test', 14:54:12 INFO - 'SHELL': '/bin/bash', 14:54:12 INFO - 'SHLVL': '1', 14:54:12 INFO - 'TERM': 'linux', 14:54:12 INFO - 'TMOUT': '86400', 14:54:12 INFO - 'USER': 'cltbld', 14:54:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:12 INFO - '_': '/tools/buildbot/bin/python'} 14:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:54:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:54:12 INFO - Cleaning up... 14:54:12 INFO - Return code: 0 14:54:12 INFO - Running post-action listener: _resource_record_post_action 14:54:12 INFO - Running post-action listener: _start_resource_monitoring 14:54:12 INFO - Starting resource monitoring. 14:54:12 INFO - [mozharness: 2016-09-16 21:54:12.574493Z] Finished create-virtualenv step (success) 14:54:12 INFO - [mozharness: 2016-09-16 21:54:12.574969Z] Running install step. 14:54:12 INFO - Running pre-action listener: _resource_record_pre_action 14:54:12 INFO - Running main action method: install 14:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:54:12 INFO - Reading from file tmpfile_stdout 14:54:12 INFO - Detecting whether we're running mozinstall >=1.0... 14:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:54:12 INFO - Reading from file tmpfile_stdout 14:54:12 INFO - Output received: 14:54:12 INFO - Usage: mozinstall [options] installer 14:54:12 INFO - Options: 14:54:12 INFO - -h, --help show this help message and exit 14:54:12 INFO - -d DEST, --destination=DEST 14:54:12 INFO - Directory to install application into. [default: 14:54:12 INFO - "/builds/slave/test"] 14:54:12 INFO - --app=APP Application being installed. [default: firefox] 14:54:12 INFO - mkdir: /builds/slave/test/build/application 14:54:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:54:30 INFO - Reading from file tmpfile_stdout 14:54:30 INFO - Output received: 14:54:30 INFO - /builds/slave/test/build/application/firefox/firefox 14:54:30 INFO - Running post-action listener: _resource_record_post_action 14:54:30 INFO - [mozharness: 2016-09-16 21:54:30.294993Z] Finished install step (success) 14:54:30 INFO - [mozharness: 2016-09-16 21:54:30.295273Z] Running run-tests step. 14:54:30 INFO - Running pre-action listener: _resource_record_pre_action 14:54:30 INFO - Running main action method: run_tests 14:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:54:30 INFO - Python 2.7.3 14:54:30 INFO - Return code: 0 14:54:30 INFO - grabbing minidump binary from tooltool 14:54:30 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')]} 14:54:30 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 0x23bb570>, '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 0x23bc470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23bc900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:54:30 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 14:54:30 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 14:54:30 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 14:54:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:54:30 INFO - Return code: 0 14:54:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:54:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:54:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:54:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:54:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:54:30 INFO - Using env: {'DISPLAY': ':0', 14:54:30 INFO - 'HOME': '/home/cltbld', 14:54:30 INFO - 'LANG': 'en_US.UTF-8', 14:54:30 INFO - 'LANGUAGE': 'en_US:en', 14:54:30 INFO - 'LOGNAME': 'cltbld', 14:54:30 INFO - 'MAIL': '/var/mail/cltbld', 14:54:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:54:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:54:30 INFO - 'MOZ_NO_REMOTE': '1', 14:54:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:54:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:54:30 INFO - 'NO_EM_RESTART': '1', 14:54:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:54:30 INFO - 'PWD': '/builds/slave/test', 14:54:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:54:30 INFO - 'SHELL': '/bin/bash', 14:54:30 INFO - 'SHLVL': '1', 14:54:30 INFO - 'TERM': 'linux', 14:54:30 INFO - 'TMOUT': '86400', 14:54:30 INFO - 'USER': 'cltbld', 14:54:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937', 14:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:54:30 INFO - '_': '/tools/buildbot/bin/python'} 14:54:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:54:30 INFO - mozversion application_buildid: 20160916120518 14:54:30 INFO - mozversion application_changeset: 8c37b5ac91e8671700cec6faed487e19f01e9e3c 14:54:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:54:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:54:30 INFO - mozversion application_name: Firefox 14:54:30 INFO - mozversion application_remotingname: firefox-dev 14:54:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:54:30 INFO - mozversion application_vendor: Mozilla 14:54:30 INFO - mozversion application_version: 50.0a2 14:54:30 INFO - mozversion platform_buildid: 20160916120518 14:54:30 INFO - mozversion platform_changeset: 8c37b5ac91e8671700cec6faed487e19f01e9e3c 14:54:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:54:30 INFO - mozversion platform_version: 50.0a2 14:54:30 INFO - using testdate: 1474062870 14:54:30 INFO - actual date: 1474062870 14:54:30 INFO - starting webserver on 'localhost:38538' 14:54:30 INFO - SUITE-START | Running 2 tests 14:54:30 INFO - TEST-START | dromaeo_css 14:54:30 INFO - operating with platform_type : linux_ 14:54:30 INFO - Initialising browser for dromaeo_css test... 14:54:30 INFO - TEST-INFO | started process 10917 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptAUQlD/profile http://localhost:38538/getInfo.html) 14:54:35 INFO - PROCESS | 10917 | __metrics Screen width/height:1600/1200 14:54:35 INFO - PROCESS | 10917 | colorDepth:24 14:54:35 INFO - PROCESS | 10917 | Browser inner width/height: 1024/697 14:54:35 INFO - PROCESS | 10917 | __metrics 14:54:36 ERROR - PROCESS | 10917 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:54:37 INFO - TEST-INFO | 10917: exit 0 14:54:37 INFO - Browser initialized. 14:54:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:54:37 INFO - TEST-INFO | started process 11046 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptAUQlD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:54:49 INFO - PROCESS | 11046 | RSS: Main: 153669632 14:54:49 INFO - PROCESS | 11046 | 14:55:39 INFO - PROCESS | 11046 | 1474062939133 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] 14:57:47 INFO - PROCESS | 11046 | Cycle 1(1): loaded http://localhost:38538/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38538/tests/dromaeo/cssquery-ext.html) 14:57:48 INFO - PROCESS | 11046 | RSS: Main: 160985088 14:57:48 INFO - PROCESS | 11046 | 15:00:50 INFO - PROCESS | 11046 | Cycle 1(1): loaded http://localhost:38538/tests/dromaeo/cssquery-ext.html (next: http://localhost:38538/tests/dromaeo/cssquery-jquery.html) 15:00:51 INFO - PROCESS | 11046 | RSS: Main: 156598272 15:00:51 INFO - PROCESS | 11046 | 15:03:53 INFO - PROCESS | 11046 | Cycle 1(1): loaded http://localhost:38538/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38538/tests/dromaeo/cssquery-mootools.html) 15:03:53 INFO - PROCESS | 11046 | RSS: Main: 156086272 15:03:53 INFO - PROCESS | 11046 | 15:06:55 INFO - PROCESS | 11046 | Cycle 1(1): loaded http://localhost:38538/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38538/tests/dromaeo/cssquery-prototype.html) 15:06:56 INFO - PROCESS | 11046 | RSS: Main: 155013120 15:06:56 INFO - PROCESS | 11046 | 15:09:58 INFO - PROCESS | 11046 | Cycle 1(1): loaded http://localhost:38538/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38538/tests/dromaeo/cssquery-yui.html) 15:09:59 INFO - PROCESS | 11046 | RSS: Main: 157417472 15:09:59 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | Cycle 1(1): loaded http://localhost:38538/tests/dromaeo/cssquery-yui.html (next: http://localhost:38538/tests/dromaeo/cssquery-dojo.html) 15:13:01 INFO - PROCESS | 11046 | RSS: Main: 158289920 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | __start_tp_report 15:13:01 INFO - PROCESS | 11046 | _x_x_mozilla_page_load 15:13:01 INFO - PROCESS | 11046 | _x_x_mozilla_page_load_details 15:13:01 INFO - PROCESS | 11046 | |i|pagename|runs| 15:13:01 INFO - PROCESS | 11046 | |0;dojo.html;3297.98351008245;3329.400707872582;3331.6168640606325;3334.4664853623417;3350.514175444559;5735.655860648359;5768.971155144223;5796.710164491779;5801.970990145049;5814.796482123126;5744.913826292598;5780.0751879699255;5791.478766910977;5794.536437085034;5833.037548804442;5198;5274.156135018378;5283.63014588979;5296.8146114885985;5317.760700768475;5068.315777370045;5084.491550844923;5114.7698353574;5121.359830021247;5137.897242055156;5200.245964335188;5205.531502164805;5240.082985477546;5267.762950667229;5319.5744340452675;5297.841064768063;5305.04509188344;5322.04203243416;5323.414424413311;5352.839414817562;4984.77568509418;5054.974725126388;5202.97398513005;5227.2159176123505;5299.8410047698635;5617.410171931929;5662.66024038559;5684.829455116312;5695.7152142392715;5710.77156913723;6128.509719222451;6144.354842741537;6167.074938759177;6185.8144255672405;6194.4115309045565;5858.2677165354335;6095.116208149793;6193.566450348478;6195.1946246988055;6215.1609532712955;5709.914351284734;5783.537317014629;5785.334686511021;5793.4785869271545;5812.738426770806;6288.591241569284;6290.874182516324;6295.464885484694;6317.305096439388;6321.3678632137335;5810.331811841692;5921.082232254007;6013.45878870895;6044.576879618385;6074.058520929263;6024.066269728111;6128.625347140905;6143.078538219303;6143.450468055797;6152.046432802923;6068.878622427526;6079.878402431927;6101.694915254219;6104.633721976696;6120.112583675342;6000.159977603139;6034;6091.878162436727;6099.390060993954;6099.542534309944;4618.584327410549;4622.375979242826;4638.721676699409;4650.162970665313;4675.579197872208;4170.082581832053;4171.0823618803815;4174.77038762871;4189.392538081961;4198.265303571924;5725.656460612377;5831.533477321804;5909.645421274738;5929.851753706105;5934.525237980951;6142.498775697809;6253.937460625427;6277.340879207709;6282.842928926722;6285.088662143963;5969.52243820502;5972.163897054415;5978.491828194652;5979.103134529855;6024.849378765566;5226.111561034633;5251.711155886386;5369.140937449989;5376.973115134399;5390.164524498709;6352.047192921099;6418.807435776934;6434.292227854932;6439.356064393523;6441.194850643669;6149.723262453111;6174.9073763892675;6177.752889884444;6217.347178546368;6224.408681175281;6155.96922015405;6255.624662520263;6277.183966084563;6433.517486188461;6443.6455994920725;6064.818055458343;6064.818055458343;6069.02895536712;6072.60528065692;6097.817065488042;6172.506199503939;6184.721687523982;6188.947878860695;6189.96905015472;6195.132681424514;5863;5940.61386009908;5966.791162309299;6048.304444988707;6060.878782424415;6019.067044608093;6040.184575082483;6043.153958445909;6063.878722425615;6067.575269731076;5912.497437717842;5937.047254252144;5978.252718410199;5987.790427335022;5999.4900433463645;5815;6020.247469066367;6035.185249991195;6040.09398590215;6071;6011.519078473624;6018.8194354169445;6020.187274718032;6020.8795824084145;6047.606905551125 15:13:01 INFO - PROCESS | 11046 | |1;ext.html;585.3853453873431;622.7975907829955;641.9614823110613;644.2076246217152;697.675580854903;39641.405378919306;45187.77406112969;45290.77354613222;45503.317450238275;45550.54449455505;45732.54267457325;46157.30764038542;46323;46353;46471;9195.724128276162;9257.074292570724;9303.209227215695;9315.627374904996;9333.206677432427;9294.535273236344;9372.906270937272;9401;9406.811863762721;9432.434053956751;9387.57755900986;9417.246620270362;9424.717258482257;9457.10157535033;9465.242780577571;9360.578773955189;9386.20217281532;9409.670661526881;9426.198773104294;9426.905730942672;9267.212286955617;9314.487703176323;9343.766405839908;9357.157855792942;9385.249180065579;43950.340744888854;44208.778956105016;44465.110697786185;44496.77751611254;44720.77639611813;3648.343298206322;3773.415120656303;3861.7876016819064;4413.757243351614;4612.677112602119;35341.293174136634;35430.822845885865;35511.64488355109;35607.64392356095;35958.10104747376;4694.201985662446;4696.553827386393;4697.906041879178;4765.373010092608;4792.017636384533;263610;266796;266910.66544667346;267355;270138;65299.347006530275;65793;65797.34202657912;65988.34011659918;66033.6698316515;39586;40211;40395;42597.57402425998;42609.573904260564;17075.658486830194;17136.22886970089;17247.65504689899;17260.654786904193;17304.221310041077;12979.675508112372;13019.934900325437;13040.413181406859;13044.869551304555;13057.412416441282;21087.578248434944;21160.894195528923;21489.35531934013;21602.783972160076;21610.89194554017;7659.808504787425;7837.255460731221;7883.329916957123;7941.404394670307;8036.4776289541;1095.611058074372;1096.331237944853;1110.6279396402135;1113.0705860606322;1222.896053835416;33978.66021339804;34374.31251374959;34490;34625.653743462746;35145;1183.0831105893;1195.8086706126978;1202.9715788006044;1211.9818202726967;1241.4475558376437;18154.18306176225;18184.818151818312;18229.17968691412;18409.815901841077;18458.81541184598;702308;705657;709425;709841;710421;67499;67519.66240168866;68011.65994169998;68197.65901170562;68282.65858670774;1445.147363055802;1454.8472410396967;1458.7593047147313;1486.4054378248786;1486.6432056306408;33182.66817331796;33742.66257337395;34030.48954265638;34344.313113738084;34523.65476345205;58036.419635803104;58261;58508.4149158503;58929.70535147297;59078.70460647669;508320;509260;519165;521622;521641;31435.214119647193;31692.524612130375;31703.207419814687;31925;32091.35817283688;52781.208281875035;53803.730981344845;54047;54129.72935135299;54186.72906635442;43933;46502.302465462366;46689.53310466852;46752.532474674816;46860.29709554291;43729.78135109304;46369.76815116038;46460.7676961613;46877.29684054674;47884.760576196895;46117.53882461267;46646.76676616595;47054.76472617615;47251;48591.514084858696;38179.61820381872;39175.60824391834;39593.80203098966;40522.797386012884;40580;30259.243518911324;30742.23144421318;30903;31212.687873120976;31284.687153128176 15:13:01 INFO - PROCESS | 11046 | |2;jquery.html;400.6338386103386;407.5862999055957;410.1121072877221;416.98957526061844;424.98512552060686;19436.319728809496;20580.10566460234;21259;21295.787042129574;21434.24980125696;19616.117274722637;19670.704939425883;20013.799862001375;20086.59826803463;20151.49621259469;5868.618539794921;6019.909701354483;6037.882991646559;6048.818535443944;6072.848178795522;5907.350191478934;5931.288245410557;5980.043193089106;5997.010493268605;6026.186464827236;5827.883442331172;5899.557533185007;5938.64368137911;5943.791967281146;5969.761209551612;5504.889902201933;5737.799177028784;5790.218320526759;5943.465088142067;5945.197398351233;5637.605367624268;5669.8582535436535;5700.429957004308;5706.4864162225185;5727.169560413717;13908;13949.790753138712;14107.36516856744;14144.85855141456;21273.574528509344;2457.668214791016;2623.4490756941063;2689.2201261634104;2713.470873179731;2898.7101289871057;9591.376560523613;9699.854502182472;9712.271579631555;9952.651657192037;10064.144547713453;1953.6092781443767;1981.2485435641615;2018.2128969701828;2033.3188381892085;2094.476380904774;86939;90774;91261.54369228178;92278.5386073072;93677.53161234218;11283.887161128448;11354.772904542027;11371.829422558669;11598.94200528992;11719.707007324885;16126.113063781604;16233;16236.837631623768;16268.75596866048;16279.430219942245;7645.885311720217;7881.408894332833;7912.0505539335645;8059.476134051268;8071.556064416513;7246.166690830517;7278.272172782786;7414.888776668355;7457.925420745723;7480.812979675552;4805.951940480621;4811.4707382187935;4865.683730557503;4866.05112003156;4932.53140951609;2060.9484762881048;2080.9271675491286;2133.0934352899953;2206.227820262895;2217.7449593296656;935.4667839331652;1045.7019749371393;1071.2608300272786;1107.092184408777;1135.4720055174218;19489.707654385194;19601.705974410394;19682.803171968382;20150.59698806045;20302.390928272176;1020.1991436722137;1031.3554028732042;1036.1710631494775;1054.3989925742253;1120.3166068698088;10617;11023.17326200538;11080.224384293022;11106.611268605722;11321.377324247082;436725;438662;442321;498889;591782;92510.53744731368;93862.14824811261;99016.50491747496;102291;105184;1030.8659874216303;1062.98937010632;1064.7391389109562;1075.887031861659;1082.155918383662;17024.48926532206;17385.652286953937;17686.646267074328;17722.379716709853;18185.272589096287;44683.77658111689;45017.54982450134;45604;46240.53759462362;46244.306335404326;63586;67410;67605.71792625643;67802;68321;7958.681652734057;7972.202779721982;8084.797880053045;8108.189181082081;8693.00030496501;11782.646520604396;13302.534411295559;13331.933340333237;13348.265845378597;13521.323933803074;11867.584634538096;11892.821607676065;11893.167478276437;12000.03999680006;12016.75966480683;11451;11795.35125568102;12019.278843269432;12052.397380131133;12082.637520874388;11415.543378264776;12232.75534489323;12358.44387002569;12392.380380981096;12594.24434533931;7696.230376962259;7815.687372505264;7840.490368126054;7960.880586791319;8083;9734.951325243328;11317.151213658844;11367;11426.457428247413;11950.2829830207 15:13:01 INFO - PROCESS | 11046 | |3;mootools.html;1544.5752418085026;1583.4616230481633;1772.1493683032145;1839.4481655503346;1881.3321270948813;8001.199880011995;8961.148690874365;9069.410488318255;9621.855672164924;9664.758381040469;9027.95486022569;9376.249900008;9392.718218453458;9417.152456278933;9429.057094290567;2482.689663792026;2515.270571534262;2533.569293220157;2534.252395543319;2540.5808041673104;2425.260295609847;2533.5439620868237;2537.9467520978774;2542.8855701493476;2545.121932933135;2479.876006199692;2489.029278581355;2521.9117330893423;2534.6958364996226;2535.936601584957;2392.294273189413;2397.7961873240797;2403.903843846244;2429.53838770633;2459.4220358215807;2410.457647029422;2425.07847018132;2429.5991161458337;2433.707955045406;2464.199135281023;9585.568649410794;9639.180669643089;9687.170544765922;9689.70930872075;9737.756556086084;5567.610267281251;5706.914396284059;5707.828765137053;5708.115242137475;5726.31284245889;3461.16931936336;3560.1455650643916;3576.2132330887425;3610.62088480711;3638.399664055428;1017.9287449878511;1061.713337398898;1127.3574062784216;1128.9613555528852;1138.1918837625296;13115.737685246242;13137.474501019948;13178.209307441584;13250.602481925558;13296.468141274241;4840.927386089212;4856.514348565115;4994.32576602154;4998.375203099613;5104.846854594368;11080.556777728962;11120.777584448428;11126.276792008493;11180.888191118147;11195.216334856645;5794.391588883106;5797.246357973437;5805.36141024487;5807.94192058074;5818;4719.339292499053;5095.980803839247;5199.948000519947;5230.424653288135;5312;4162.625363717219;4230.788460576959;4305.182015417061;4327.653787696977;4348.934765978513;1330.3680751643085;1336.8462626797848;1339.2500199888093;1374.1068305601239;1378.3315092180285;1009.2178561614808;1036.533559898049;1114.5931734916696;1114.7157474844025;1118.9888101119047;6416.069669897839;6437.452816510649;6617.536772425884;6623.503237257225;6796.592204467748;458.4796256249181;458.8233530090872;466.6523440037194;472.7305435901503;474.5491782806348;4653.511381304914;4708.246680531099;4733.246745521061;4778.617710583145;4798.1123492154065;10929.617463388744;11342.489587968561;11362.431878406047;11378.31730096197;11415.315081094996;11066.446677666085;11260.324380537195;11275.718107047389;11460.598879039193;11508.88491115078;8675.436096653824;8791.648334066693;8807.559622018745;8834.425762325556;8875.112488751074;6407.199100112486;6650.4347130492515;6655.633940133145;6741.426978706865;6849.1096157499205;10745.409002504937;11066;11074.833877491683;11150.721231969266;11219.887801122211;11025;11231.15766317513;11236.325820450798;11348.319100853974;11395.031422329195;7331.486795924234;8544.829103418022;8570.48577085377;8623.56882155877;8968.461892286483;9089.363744537819;9428.434293942386;9440.244780417413;9443.575039123394;9455.338126331091;8116.107228204774;8127.349812015143;8260.876086858581;8275.255227029596;8287.378446616649;7828.452008359588;7952.833670853216;7994.360451163777;8044.798880027812;8329.791755205926;7458.993035940296;7460.962695186489;7489.176190619137;7579.279968402993;7652.196519365387;5321.36143662763;5383.757730902041;5454.59090568201;5457.61796674229;5511.173324001432;5277.20841873722;5382.327209098863;5401.243825864303;5440.917370920134;5508.421615730291 15:13:01 INFO - PROCESS | 11046 | |4;prototype.html;321.1265358225626;321.18259030766694;322.10454935279915;348.4181417033554;349.1881375801263;5740.79907203248;5824.90303490743;5852.941470585293;5861.384554621767;5861.765529378819;5659.405762394951;5688.687122208287;5714.771409143629;5733.827985160441;5740.397258287871;4254.829806807724;4328.3074708046715;4454.821807127711;4466;4467.865964021084;4449.377087207794;4450.977745111286;4451.821927122911;4470.8882277942985;4473.284274516078;4440.7335559866515;4444.73331600105;4456.732596044248;4460.30865215892;4467.687261891669;4321.567843215685;4453.888652783674;4476.127155204753;4480.372747815309;4491.528389519086;3972.781497017663;3991.7604943703473;3996.140829721595;4000.599940006005;4010.8796736097966;5578.748956296977;5596.608237423382;5601.579881508903;5603.971980140073;5615.634983726045;4983.401991761012;5115.897682046376;5152.407473140599;5153.994970980661;5164.225366195101;5958.940410595926;5969.492593129589;5979;5981.8634459453;5993.790217342415;4372.360364863176;4378.6934914555995;4386.71486353386;4391.077873646538;4392.363107349448;5960.195373624549;6151.6001459904965;6247.250329960389;6254.843628909268;6256.217972753406;4234.597713217239;4283.657307415461;4296.2911119664905;4297.431437525343;4298.140371925628;4225.576011684208;4324.329728892027;4332.415123958288;4347.891302717394;4357.498887627963;4912.533309335608;4951.653384263139;4996.6502344836235;5022.96110852936;5026.004326560394;6023.247094113236;6024.186734790835;6033.788817391458;6054.182685337511;6071.787487437919;1524.8475152484884;1549.403479660339;1559.033399292446;1561.4691005058107;1568.749000159969;991.1624677147798;1015.3298822776932;1029.552144816992;1038.5638032026568;1054.5201933120566;5743.741531631086;5808.564357673192;5820.476157145878;5824.242848429762;5863.20846685692;6092.75628974836;6168.599041062316;6202.379762023852;6229.813105606839;6246.906296405557;5680.005998950167;5893.615000474885;5909.734061967221;5971.313298970587;5997.880042399128;3275.9344813103603;3323.4516304809918;3339.780979942352;3342.5821772278464;3358.7313014958745;6241.751649670085;6384.585001974857;6424.453921416732;6455.870882582322;6463.2244130703675;6288.4593274648005;6323.747050118036;6326.6520341381665;6328.367163283635;6340.1440805490665;6123.571350005457;6374.521910856704;6411.0383442484;6437.746788623944;6473.546851720429;6186.010238362021;6267.114389078801;6277.905831412624;6284.74861005573;6293.213348331458;6174.536909731878;6175;6184.474319682878;6207.410296021871;6232.875342493216;6290.46531044848;6363.245685045845;6454.322296159024;6479.509712765996;6480.898247298065;4396.978015109904;4408.162449134654;4425.955740442683;4438.7114837535455;4440.400545926259;6045.818625441244;6188.133661287513;6271.96864015677;6275.435210830865;6301.747930082745;6077.2099627049965;6083.361247068994;6093.29927058394;6103.725332359965;6139.171211886517;6082.482988945812;6096.695165241809;6109.991851344656;6118;6120;5825.76696932118;5851.327097383685;5896.852578685567;5985.940140598713;6015.015045035216;6104.298005729398;6120.234970628671;6123;6143.877122457615;6169.96915015422;5809.2738407699035;5965.761369545343;5970.164177015135;5987.730552125077;5999.5800293979 15:13:01 INFO - PROCESS | 11046 | |5;yui.html;736.8342123022321;785.8781888807237;826.6156237349632;840.0675250471979;943.5989704375638;16148.273327700252;17087.23107460164;17342.47972560822;17352.65294694107;17375.218115184816;15635.84364156358;16255.674886502293;16402;16932.915335423306;17142.485725428258;871.6034204436982;890.8262888736699;890.9376343655947;891.1578558262445;891.393852180517;865.1132589096164;898.128815049401;900.6262401103534;903.1194585279366;903.4895284164459;927.0081013315755;929.8000929800091;933.2906990687059;934.028610245344;936.4615346175942;921.9812107621116;926.8656044873455;930.1721467893581;931.3573634192386;931.3852857114306;683.9726410943606;689.0938415983017;689.689639662149;689.8620275944832;690.927452617478;16323;16408.26162822676;16441;16455.177241138015;16535.925164864246;16407.67184656312;16641.583960400967;16712.24794884233;16721.749173762404;16819.83180168195;13036.608901732963;13078.280694561796;13236.272005039718;13288.933555332258;13464.798028029587;4210.768407737573;4267.338562522814;4272.743635381887;4371.103923695635;4415.8233670653135;88820.55589722075;88833.99999999936;89025.55487222587;90132.54933725289;90584;11923.403829808649;12369.196002260005;12747.107702460924;12808.295543745184;12862;25842.095526656478;26139.73860261411;26181.607275890878;26610.201693949213;26718;8816.471011739317;8876.289896808239;8930.151635594739;8989.325800564984;9145.679901203426;4601.7699115044115;4671.135839869635;4682.180618391768;4711.434627844681;4712.034033023222;3801.429785532137;3937.995811068159;3969.563348031714;3974.364101743707;3985.880423587297;668.204836244867;670.577536152221;671.8656268746271;681.4582406986458;688.8484533402643;653.3531803514486;737.5611511150856;757.3184134279193;761.2425636491615;773.555205756681;10007.14939230159;10041.899581004243;10210.897891021143;10211.540480678388;10790.56837726482;682.6654939079815;688.1295161620554;703.426707233603;717.0606505477841;723.9312265334785;4616.403367704051;4860.052289803454;4869.464358920516;4885.340479035285;4899.608031357483;129918;133733.3313333427;137397.3130134343;139571.30214348863;140441;114205;119557;120076;121572.99999999822;123235.3838230821;60068.699656501434;102537;103851.4807425958;105266.47366763117;106145.4692726547;9223.723288301362;9247.260219182584;9346.597580657271;9473.715788526355;9579.521023948913;49241;58419.70790146022;58865.70567147137;59754.103688443836;60651;49438.258426124354;66311.66844165749;75389.6230518844;75954.24045759471;80104;13781.655458613615;13895.305234738425;14055.22696251716;14102;14209.644758881112;16687.833121668627;17006.404775832787;17062.744058838878;17100.91449542744;17401.21694523775;14090.718185636024;14335.808683455343;14640.70718585601;15498;16741.414050508174;14332.57002289933;14410.42358305656;14795.482158124414;14895.702085958437;15011.474598389004;13842.723145537235;14009.43962241499;14700.264986750833;14741.484048058266;14765.187914664797;11126.777464450828;11220.607278745205;11933.28400295985;12021.819672704742;12509;12460.439280232593;12562.309073000708;12593.811092833432;12686.555970540987;12929.418176181905 15:13:01 INFO - PROCESS | 11046 | __end_tp_report 15:13:01 INFO - PROCESS | 11046 | __start_cc_report 15:13:01 INFO - PROCESS | 11046 | _x_x_mozilla_cycle_collect,345 15:13:01 INFO - PROCESS | 11046 | __end_cc_report 15:13:01 INFO - PROCESS | 11046 | __startTimestamp1474063981807__endTimestamp 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | ------- Summary: start ------- 15:13:01 INFO - PROCESS | 11046 | Number of tests: 6 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | [#0] dojo.html Cycles:165 Average:5718.52 Median:5978.49 stddev:656.72 (11.0%) stddev-sans-first:630.68 15:13:01 INFO - PROCESS | 11046 | Values: 3298.0 3329.4 3331.6 3334.5 3350.5 5735.7 5769.0 5796.7 5802.0 5814.8 5744.9 5780.1 5791.5 5794.5 5833.0 5198.0 5274.2 5283.6 5296.8 5317.8 5068.3 5084.5 5114.8 5121.4 5137.9 5200.2 5205.5 5240.1 5267.8 5319.6 5297.8 5305.0 5322.0 5323.4 5352.8 4984.8 5055.0 5203.0 5227.2 5299.8 5617.4 5662.7 5684.8 5695.7 5710.8 6128.5 6144.4 6167.1 6185.8 6194.4 5858.3 6095.1 6193.6 6195.2 6215.2 5709.9 5783.5 5785.3 5793.5 5812.7 6288.6 6290.9 6295.5 6317.3 6321.4 5810.3 5921.1 6013.5 6044.6 6074.1 6024.1 6128.6 6143.1 6143.5 6152.0 6068.9 6079.9 6101.7 6104.6 6120.1 6000.2 6034.0 6091.9 6099.4 6099.5 4618.6 4622.4 4638.7 4650.2 4675.6 4170.1 4171.1 4174.8 4189.4 4198.3 5725.7 5831.5 5909.6 5929.9 5934.5 6142.5 6253.9 6277.3 6282.8 6285.1 5969.5 5972.2 5978.5 5979.1 6024.8 5226.1 5251.7 5369.1 5377.0 5390.2 6352.0 6418.8 6434.3 6439.4 6441.2 6149.7 6174.9 6177.8 6217.3 6224.4 6156.0 6255.6 6277.2 6433.5 6443.6 6064.8 6064.8 6069.0 6072.6 6097.8 6172.5 6184.7 6188.9 6190.0 6195.1 5863.0 5940.6 5966.8 6048.3 6060.9 6019.1 6040.2 6043.2 6063.9 6067.6 5912.5 5937.0 5978.3 5987.8 5999.5 5815.0 6020.2 6035.2 6040.1 6071.0 6011.5 6018.8 6020.2 6020.9 6047.6 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | [#1] ext.html Cycles:180 Average:66559.03 Median:45641.54 stddev:141534.86 (310.1%) stddev-sans-first:141845.22 15:13:01 INFO - PROCESS | 11046 | Values: 585.4 622.8 642.0 644.2 697.7 39641.4 45187.8 45290.8 45503.3 45550.5 45732.5 46157.3 46323.0 46353.0 46471.0 9195.7 9257.1 9303.2 9315.6 9333.2 9294.5 9372.9 9401.0 9406.8 9432.4 9387.6 9417.2 9424.7 9457.1 9465.2 9360.6 9386.2 9409.7 9426.2 9426.9 9267.2 9314.5 9343.8 9357.2 9385.2 43950.3 44208.8 44465.1 44496.8 44720.8 3648.3 3773.4 3861.8 4413.8 4612.7 35341.3 35430.8 35511.6 35607.6 35958.1 4694.2 4696.6 4697.9 4765.4 4792.0 263610.0 266796.0 266910.7 267355.0 270138.0 65299.3 65793.0 65797.3 65988.3 66033.7 39586.0 40211.0 40395.0 42597.6 42609.6 17075.7 17136.2 17247.7 17260.7 17304.2 12979.7 13019.9 13040.4 13044.9 13057.4 21087.6 21160.9 21489.4 21602.8 21610.9 7659.8 7837.3 7883.3 7941.4 8036.5 1095.6 1096.3 1110.6 1113.1 1222.9 33978.7 34374.3 34490.0 34625.7 35145.0 1183.1 1195.8 1203.0 1212.0 1241.4 18154.2 18184.8 18229.2 18409.8 18458.8 702308.0 705657.0 709425.0 709841.0 710421.0 67499.0 67519.7 68011.7 68197.7 68282.7 1445.1 1454.8 1458.8 1486.4 1486.6 33182.7 33742.7 34030.5 34344.3 34523.7 58036.4 58261.0 58508.4 58929.7 59078.7 508320.0 509260.0 519165.0 521622.0 521641.0 31435.2 31692.5 31703.2 31925.0 32091.4 52781.2 53803.7 54047.0 54129.7 54186.7 43933.0 46502.3 46689.5 46752.5 46860.3 43729.8 46369.8 46460.8 46877.3 47884.8 46117.5 46646.8 47054.8 47251.0 48591.5 38179.6 39175.6 39593.8 40522.8 40580.0 30259.2 30742.2 30903.0 31212.7 31284.7 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | [#2] jquery.html Cycles:180 Average:29502.83 Median:2337.71 stddev:80572.76 (3446.7%) stddev-sans-first:80769.16 15:13:01 INFO - PROCESS | 11046 | Values: 400.6 407.6 410.1 417.0 425.0 19436.3 20580.1 21259.0 21295.8 21434.2 19616.1 19670.7 20013.8 20086.6 20151.5 5868.6 6019.9 6037.9 6048.8 6072.8 5907.4 5931.3 5980.0 5997.0 6026.2 5827.9 5899.6 5938.6 5943.8 5969.8 5504.9 5737.8 5790.2 5943.5 5945.2 5637.6 5669.9 5700.4 5706.5 5727.2 13908.0 13949.8 14107.4 14144.9 21273.6 2457.7 2623.4 2689.2 2713.5 2898.7 9591.4 9699.9 9712.3 9952.7 10064.1 1953.6 1981.2 2018.2 2033.3 2094.5 86939.0 90774.0 91261.5 92278.5 93677.5 11283.9 11354.8 11371.8 11598.9 11719.7 16126.1 16233.0 16236.8 16268.8 16279.4 7645.9 7881.4 7912.1 8059.5 8071.6 7246.2 7278.3 7414.9 7457.9 7480.8 4806.0 4811.5 4865.7 4866.1 4932.5 2060.9 2080.9 2133.1 2206.2 2217.7 935.5 1045.7 1071.3 1107.1 1135.5 19489.7 19601.7 19682.8 20150.6 20302.4 1020.2 1031.4 1036.2 1054.4 1120.3 10617.0 11023.2 11080.2 11106.6 11321.4 436725.0 438662.0 442321.0 498889.0 591782.0 92510.5 93862.1 99016.5 102291.0 105184.0 1030.9 1063.0 1064.7 1075.9 1082.2 17024.5 17385.7 17686.6 17722.4 18185.3 44683.8 45017.5 45604.0 46240.5 46244.3 63586.0 67410.0 67605.7 67802.0 68321.0 7958.7 7972.2 8084.8 8108.2 8693.0 11782.6 13302.5 13331.9 13348.3 13521.3 11867.6 11892.8 11893.2 12000.0 12016.8 11451.0 11795.4 12019.3 12052.4 12082.6 11415.5 12232.8 12358.4 12392.4 12594.2 7696.2 7815.7 7840.5 7960.9 8083.0 9735.0 11317.2 11367.0 11426.5 11950.3 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | [#3] mootools.html Cycles:180 Average:6221.17 Median:2596.03 stddev:3551.93 (136.8%) stddev-sans-first:3544.51 15:13:01 INFO - PROCESS | 11046 | Values: 1544.6 1583.5 1772.1 1839.4 1881.3 8001.2 8961.1 9069.4 9621.9 9664.8 9028.0 9376.2 9392.7 9417.2 9429.1 2482.7 2515.3 2533.6 2534.3 2540.6 2425.3 2533.5 2537.9 2542.9 2545.1 2479.9 2489.0 2521.9 2534.7 2535.9 2392.3 2397.8 2403.9 2429.5 2459.4 2410.5 2425.1 2429.6 2433.7 2464.2 9585.6 9639.2 9687.2 9689.7 9737.8 5567.6 5706.9 5707.8 5708.1 5726.3 3461.2 3560.1 3576.2 3610.6 3638.4 1017.9 1061.7 1127.4 1129.0 1138.2 13115.7 13137.5 13178.2 13250.6 13296.5 4840.9 4856.5 4994.3 4998.4 5104.8 11080.6 11120.8 11126.3 11180.9 11195.2 5794.4 5797.2 5805.4 5807.9 5818.0 4719.3 5096.0 5199.9 5230.4 5312.0 4162.6 4230.8 4305.2 4327.7 4348.9 1330.4 1336.8 1339.3 1374.1 1378.3 1009.2 1036.5 1114.6 1114.7 1119.0 6416.1 6437.5 6617.5 6623.5 6796.6 458.5 458.8 466.7 472.7 474.5 4653.5 4708.2 4733.2 4778.6 4798.1 10929.6 11342.5 11362.4 11378.3 11415.3 11066.4 11260.3 11275.7 11460.6 11508.9 8675.4 8791.6 8807.6 8834.4 8875.1 6407.2 6650.4 6655.6 6741.4 6849.1 10745.4 11066.0 11074.8 11150.7 11219.9 11025.0 11231.2 11236.3 11348.3 11395.0 7331.5 8544.8 8570.5 8623.6 8968.5 9089.4 9428.4 9440.2 9443.6 9455.3 8116.1 8127.3 8260.9 8275.3 8287.4 7828.5 7952.8 7994.4 8044.8 8329.8 7459.0 7461.0 7489.2 7579.3 7652.2 5321.4 5383.8 5454.6 5457.6 5511.2 5277.2 5382.3 5401.2 5440.9 5508.4 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | [#4] prototype.html Cycles:180 Average:5085.29 Median:5824.57 stddev:1507.99 (25.9%) stddev-sans-first:1469.21 15:13:01 INFO - PROCESS | 11046 | Values: 321.1 321.2 322.1 348.4 349.2 5740.8 5824.9 5852.9 5861.4 5861.8 5659.4 5688.7 5714.8 5733.8 5740.4 4254.8 4328.3 4454.8 4466.0 4467.9 4449.4 4451.0 4451.8 4470.9 4473.3 4440.7 4444.7 4456.7 4460.3 4467.7 4321.6 4453.9 4476.1 4480.4 4491.5 3972.8 3991.8 3996.1 4000.6 4010.9 5578.7 5596.6 5601.6 5604.0 5615.6 4983.4 5115.9 5152.4 5154.0 5164.2 5958.9 5969.5 5979.0 5981.9 5993.8 4372.4 4378.7 4386.7 4391.1 4392.4 5960.2 6151.6 6247.3 6254.8 6256.2 4234.6 4283.7 4296.3 4297.4 4298.1 4225.6 4324.3 4332.4 4347.9 4357.5 4912.5 4951.7 4996.7 5023.0 5026.0 6023.2 6024.2 6033.8 6054.2 6071.8 1524.8 1549.4 1559.0 1561.5 1568.7 991.2 1015.3 1029.6 1038.6 1054.5 5743.7 5808.6 5820.5 5824.2 5863.2 6092.8 6168.6 6202.4 6229.8 6246.9 5680.0 5893.6 5909.7 5971.3 5997.9 3275.9 3323.5 3339.8 3342.6 3358.7 6241.8 6384.6 6424.5 6455.9 6463.2 6288.5 6323.7 6326.7 6328.4 6340.1 6123.6 6374.5 6411.0 6437.7 6473.5 6186.0 6267.1 6277.9 6284.7 6293.2 6174.5 6175.0 6184.5 6207.4 6232.9 6290.5 6363.2 6454.3 6479.5 6480.9 4397.0 4408.2 4426.0 4438.7 4440.4 6045.8 6188.1 6272.0 6275.4 6301.7 6077.2 6083.4 6093.3 6103.7 6139.2 6082.5 6096.7 6110.0 6118.0 6120.0 5825.8 5851.3 5896.9 5985.9 6015.0 6104.3 6120.2 6123.0 6143.9 6170.0 5809.3 5965.8 5970.2 5987.7 5999.6 15:13:01 INFO - PROCESS | 11046 | 15:13:01 INFO - PROCESS | 11046 | [#5] yui.html Cycles:180 Average:23326.00 Median:3953.78 stddev:34643.61 (876.2%) stddev-sans-first:34699.27 15:13:01 INFO - PROCESS | 11046 | Values: 736.8 785.9 826.6 840.1 943.6 16148.3 17087.2 17342.5 17352.7 17375.2 15635.8 16255.7 16402.0 16932.9 17142.5 871.6 890.8 890.9 891.2 891.4 865.1 898.1 900.6 903.1 903.5 927.0 929.8 933.3 934.0 936.5 922.0 926.9 930.2 931.4 931.4 684.0 689.1 689.7 689.9 690.9 16323.0 16408.3 16441.0 16455.2 16535.9 16407.7 16641.6 16712.2 16721.7 16819.8 13036.6 13078.3 13236.3 13288.9 13464.8 4210.8 4267.3 4272.7 4371.1 4415.8 88820.6 88834.0 89025.6 90132.5 90584.0 11923.4 12369.2 12747.1 12808.3 12862.0 25842.1 26139.7 26181.6 26610.2 26718.0 8816.5 8876.3 8930.2 8989.3 9145.7 4601.8 4671.1 4682.2 4711.4 4712.0 3801.4 3938.0 3969.6 3974.4 3985.9 668.2 670.6 671.9 681.5 688.8 653.4 737.6 757.3 761.2 773.6 10007.1 10041.9 10210.9 10211.5 10790.6 682.7 688.1 703.4 717.1 723.9 4616.4 4860.1 4869.5 4885.3 4899.6 129918.0 133733.3 137397.3 139571.3 140441.0 114205.0 119557.0 120076.0 121573.0 123235.4 60068.7 102537.0 103851.5 105266.5 106145.5 9223.7 9247.3 9346.6 9473.7 9579.5 49241.0 58419.7 58865.7 59754.1 60651.0 49438.3 66311.7 75389.6 75954.2 80104.0 13781.7 13895.3 14055.2 14102.0 14209.6 16687.8 17006.4 17062.7 17100.9 17401.2 14090.7 14335.8 14640.7 15498.0 16741.4 14332.6 14410.4 14795.5 14895.7 15011.5 13842.7 14009.4 14700.3 14741.5 14765.2 11126.8 11220.6 11933.3 12021.8 12509.0 12460.4 12562.3 12593.8 12686.6 12929.4 15:13:01 INFO - PROCESS | 11046 | -------- Summary: end -------- 15:13:01 INFO - PROCESS | 11046 | 15:13:02 INFO - TEST-INFO | 11046: exit 0 15:13:02 INFO - TEST-OK | dromaeo_css | took 1112033ms 15:13:02 INFO - TEST-START | kraken 15:13:02 INFO - operating with platform_type : linux_ 15:13:02 INFO - Initialising browser for kraken test... 15:13:02 INFO - TEST-INFO | started process 11312 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4W25ot/profile http://localhost:38538/getInfo.html) 15:13:07 INFO - PROCESS | 11312 | __metrics Screen width/height:1600/1200 15:13:07 INFO - PROCESS | 11312 | colorDepth:24 15:13:07 INFO - PROCESS | 11312 | Browser inner width/height: 1024/697 15:13:07 INFO - PROCESS | 11312 | __metrics 15:13:08 ERROR - PROCESS | 11312 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 15:13:09 INFO - TEST-INFO | 11312: exit 0 15:13:09 INFO - Browser initialized. 15:13:09 INFO - Running cycle 1/1 for kraken test... 15:13:09 INFO - TEST-INFO | started process 11441 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4W25ot/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:13:21 INFO - PROCESS | 11441 | RSS: Main: 153141248 15:13:21 INFO - PROCESS | 11441 | 15:13:23 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38538/tests/kraken/driver.html?testName=audio-beat-detection) 15:13:24 INFO - PROCESS | 11441 | RSS: Main: 158789632 15:13:24 INFO - PROCESS | 11441 | 15:13:27 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38538/tests/kraken/driver.html?testName=audio-dft) 15:13:27 INFO - PROCESS | 11441 | RSS: Main: 157360128 15:13:27 INFO - PROCESS | 11441 | 15:13:30 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38538/tests/kraken/driver.html?testName=audio-fft) 15:13:31 INFO - PROCESS | 11441 | RSS: Main: 158560256 15:13:31 INFO - PROCESS | 11441 | 15:13:33 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38538/tests/kraken/driver.html?testName=audio-oscillator) 15:13:34 INFO - PROCESS | 11441 | RSS: Main: 158474240 15:13:34 INFO - PROCESS | 11441 | 15:13:36 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38538/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:13:36 INFO - PROCESS | 11441 | RSS: Main: 158126080 15:13:36 INFO - PROCESS | 11441 | 15:13:40 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38538/tests/kraken/driver.html?testName=imaging-darkroom) 15:13:40 INFO - PROCESS | 11441 | RSS: Main: 157671424 15:13:40 INFO - PROCESS | 11441 | 15:13:44 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38538/tests/kraken/driver.html?testName=imaging-desaturate) 15:13:44 INFO - PROCESS | 11441 | RSS: Main: 155799552 15:13:44 INFO - PROCESS | 11441 | 15:13:47 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38538/tests/kraken/driver.html?testName=json-parse-financial) 15:13:48 INFO - PROCESS | 11441 | RSS: Main: 157659136 15:13:48 INFO - PROCESS | 11441 | 15:13:49 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38538/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:13:50 INFO - PROCESS | 11441 | RSS: Main: 157569024 15:13:50 INFO - PROCESS | 11441 | 15:13:52 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:13:52 INFO - PROCESS | 11441 | RSS: Main: 157450240 15:13:52 INFO - PROCESS | 11441 | 15:13:54 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:13:55 INFO - PROCESS | 11441 | RSS: Main: 158306304 15:13:55 INFO - PROCESS | 11441 | 15:13:58 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:13:58 INFO - PROCESS | 11441 | RSS: Main: 158081024 15:13:58 INFO - PROCESS | 11441 | 15:14:01 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:14:01 INFO - PROCESS | 11441 | RSS: Main: 158076928 15:14:01 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | Cycle 1(1): loaded http://localhost:38538/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38538/tests/kraken/driver.html?testName=ai-astar) 15:14:03 INFO - PROCESS | 11441 | RSS: Main: 160714752 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | __start_tp_report 15:14:03 INFO - PROCESS | 11441 | _x_x_mozilla_page_load 15:14:03 INFO - PROCESS | 11441 | _x_x_mozilla_page_load_details 15:14:03 INFO - PROCESS | 11441 | |i|pagename|runs| 15:14:03 INFO - PROCESS | 11441 | |0;ai-astar;100;109;110;106;110;110;110;110;112;122 15:14:03 INFO - PROCESS | 11441 | |1;audio-beat-detection;150;114;140;174;122;140;143;119;188;118 15:14:03 INFO - PROCESS | 11441 | |2;audio-dft;162;162;164;162;161;160;161;160;162;163 15:14:03 INFO - PROCESS | 11441 | |3;audio-fft;75;75;76;167;75;75;76;74;168;77 15:14:03 INFO - PROCESS | 11441 | |4;audio-oscillator;83;84;150;83;84;86;147;83;82;141 15:14:03 INFO - PROCESS | 11441 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;118;118 15:14:03 INFO - PROCESS | 11441 | |6;imaging-darkroom;147;147;147;147;147;147;148;147;153;146 15:14:03 INFO - PROCESS | 11441 | |7;imaging-desaturate;95;95;97;95;95;95;95;96;96;96 15:14:03 INFO - PROCESS | 11441 | |8;json-parse-financial;53;53;52;54;53;53;60;54;53;54 15:14:03 INFO - PROCESS | 11441 | |9;json-stringify-tinderbox;59;59;59;59;63;59;59;59;59;61 15:14:03 INFO - PROCESS | 11441 | |10;stanford-crypto-aes;73;73;72;72;83;74;77;72;78;72 15:14:03 INFO - PROCESS | 11441 | |11;stanford-crypto-ccm;118;126;120;117;127;121;116;129;156;123 15:14:03 INFO - PROCESS | 11441 | |12;stanford-crypto-pbkdf2;155;155;153;149;158;154;153;151;151;151 15:14:03 INFO - PROCESS | 11441 | |13;stanford-crypto-sha256-iterative;67;65;63;65;61;64;61;62;68;60 15:14:03 INFO - PROCESS | 11441 | __end_tp_report 15:14:03 INFO - PROCESS | 11441 | __start_cc_report 15:14:03 INFO - PROCESS | 11441 | _x_x_mozilla_cycle_collect,878 15:14:03 INFO - PROCESS | 11441 | __end_cc_report 15:14:03 INFO - PROCESS | 11441 | __startTimestamp1474064043820__endTimestamp 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | ------- Summary: start ------- 15:14:03 INFO - PROCESS | 11441 | Number of tests: 14 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.00 stddev:5.43 (4.9%) stddev-sans-first:4.42 15:14:03 INFO - PROCESS | 11441 | Values: 100.0 109.0 110.0 106.0 110.0 110.0 110.0 110.0 112.0 122.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:141.50 stddev:24.69 (17.5%) stddev-sans-first:25.97 15:14:03 INFO - PROCESS | 11441 | Values: 150.0 114.0 140.0 174.0 122.0 140.0 143.0 119.0 188.0 118.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 15:14:03 INFO - PROCESS | 11441 | Values: 162.0 162.0 164.0 162.0 161.0 160.0 161.0 160.0 162.0 163.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#3] audio-fft Cycles:10 Average:93.80 Median:75.00 stddev:38.85 (51.8%) stddev-sans-first:40.61 15:14:03 INFO - PROCESS | 11441 | Values: 75.0 75.0 76.0 167.0 75.0 75.0 76.0 74.0 168.0 77.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#4] audio-oscillator Cycles:10 Average:102.30 Median:83.00 stddev:30.25 (36.4%) stddev-sans-first:31.27 15:14:03 INFO - PROCESS | 11441 | Values: 83.0 84.0 150.0 83.0 84.0 86.0 147.0 83.0 82.0 141.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 15:14:03 INFO - PROCESS | 11441 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 118.0 118.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:147.00 stddev:1.96 (1.3%) stddev-sans-first:2.06 15:14:03 INFO - PROCESS | 11441 | Values: 147.0 147.0 147.0 147.0 147.0 147.0 148.0 147.0 153.0 146.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 15:14:03 INFO - PROCESS | 11441 | Values: 95.0 95.0 97.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:2.23 (4.2%) stddev-sans-first:2.35 15:14:03 INFO - PROCESS | 11441 | Values: 53.0 53.0 52.0 54.0 53.0 53.0 60.0 54.0 53.0 54.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:1.35 (2.3%) stddev-sans-first:1.41 15:14:03 INFO - PROCESS | 11441 | Values: 59.0 59.0 59.0 59.0 63.0 59.0 59.0 59.0 59.0 61.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:73.50 stddev:3.66 (5.0%) stddev-sans-first:3.83 15:14:03 INFO - PROCESS | 11441 | Values: 73.0 73.0 72.0 72.0 83.0 74.0 77.0 72.0 78.0 72.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:124.50 stddev:11.64 (9.4%) stddev-sans-first:12.05 15:14:03 INFO - PROCESS | 11441 | Values: 118.0 126.0 120.0 117.0 127.0 121.0 116.0 129.0 156.0 123.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:153.50 stddev:2.62 (1.7%) stddev-sans-first:2.68 15:14:03 INFO - PROCESS | 11441 | Values: 155.0 155.0 153.0 149.0 158.0 154.0 153.0 151.0 151.0 151.0 15:14:03 INFO - PROCESS | 11441 | 15:14:03 INFO - PROCESS | 11441 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.50 stddev:2.67 (4.1%) stddev-sans-first:2.54 15:14:03 INFO - PROCESS | 11441 | Values: 67.0 65.0 63.0 65.0 61.0 64.0 61.0 62.0 68.0 60.0 15:14:03 INFO - PROCESS | 11441 | -------- Summary: end -------- 15:14:03 INFO - PROCESS | 11441 | 15:14:04 INFO - TEST-INFO | 11441: exit 0 15:14:04 INFO - TEST-OK | kraken | took 62189ms 15:14:04 INFO - SUITE-END | took 1174s 15:14:04 INFO - Completed test suite (00:19:34) 15:14:04 INFO - javascript benchmark 15:14:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7726.112132973008, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.98351008245, 3329.400707872582, 3331.6168640606325, 3334.4664853623417, 3350.514175444559, 5735.655860648359, 5768.971155144223, 5796.710164491779, 5801.970990145049, 5814.796482123126, 5744.913826292598, 5780.0751879699255, 5791.478766910977, 5794.536437085034, 5833.037548804442, 5198.0, 5274.156135018378, 5283.63014588979, 5296.8146114885985, 5317.760700768475, 5068.315777370045, 5084.491550844923, 5114.7698353574, 5121.359830021247, 5137.897242055156, 5200.245964335188, 5205.531502164805, 5240.082985477546, 5267.762950667229, 5319.5744340452675, 5297.841064768063, 5305.04509188344, 5322.04203243416, 5323.414424413311, 5352.839414817562, 4984.77568509418, 5054.974725126388, 5202.97398513005, 5227.2159176123505, 5299.8410047698635, 5617.410171931929, 5662.66024038559, 5684.829455116312, 5695.7152142392715, 5710.77156913723, 6128.509719222451, 6144.354842741537, 6167.074938759177, 6185.8144255672405, 6194.4115309045565, 5858.2677165354335, 6095.116208149793, 6193.566450348478, 6195.1946246988055, 6215.1609532712955, 5709.914351284734, 5783.537317014629, 5785.334686511021, 5793.4785869271545, 5812.738426770806, 6288.591241569284, 6290.874182516324, 6295.464885484694, 6317.305096439388, 6321.3678632137335, 5810.331811841692, 5921.082232254007, 6013.45878870895, 6044.576879618385, 6074.058520929263, 6024.066269728111, 6128.625347140905, 6143.078538219303, 6143.450468055797, 6152.046432802923, 6068.878622427526, 6079.878402431927, 6101.694915254219, 6104.633721976696, 6120.112583675342, 6000.159977603139, 6034.0, 6091.878162436727, 6099.390060993954, 6099.542534309944, 4618.584327410549, 4622.375979242826, 4638.721676699409, 4650.162970665313, 4675.579197872208, 4170.082581832053, 4171.0823618803815, 4174.77038762871, 4189.392538081961, 4198.265303571924, 5725.656460612377, 5831.533477321804, 5909.645421274738, 5929.851753706105, 5934.525237980951, 6142.498775697809, 6253.937460625427, 6277.340879207709, 6282.842928926722, 6285.088662143963, 5969.52243820502, 5972.163897054415, 5978.491828194652, 5979.103134529855, 6024.849378765566, 5226.111561034633, 5251.711155886386, 5369.140937449989, 5376.973115134399, 5390.164524498709, 6352.047192921099, 6418.807435776934, 6434.292227854932, 6439.356064393523, 6441.194850643669, 6149.723262453111, 6174.9073763892675, 6177.752889884444, 6217.347178546368, 6224.408681175281, 6155.96922015405, 6255.624662520263, 6277.183966084563, 6433.517486188461, 6443.6455994920725, 6064.818055458343, 6064.818055458343, 6069.02895536712, 6072.60528065692, 6097.817065488042, 6172.506199503939, 6184.721687523982, 6188.947878860695, 6189.96905015472, 6195.132681424514, 5863.0, 5940.61386009908, 5966.791162309299, 6048.304444988707, 6060.878782424415, 6019.067044608093, 6040.184575082483, 6043.153958445909, 6063.878722425615, 6067.575269731076, 5912.497437717842, 5937.047254252144, 5978.252718410199, 5987.790427335022, 5999.4900433463645, 5815.0, 6020.247469066367, 6035.185249991195, 6040.09398590215, 6071.0, 6011.519078473624, 6018.8194354169445, 6020.187274718032, 6020.8795824084145, 6047.606905551125], "value": 5673.628066830648, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.3853453873431, 622.7975907829955, 641.9614823110613, 644.2076246217152, 697.675580854903, 39641.405378919306, 45187.77406112969, 45290.77354613222, 45503.317450238275, 45550.54449455505, 45732.54267457325, 46157.30764038542, 46323.0, 46353.0, 46471.0, 9195.724128276162, 9257.074292570724, 9303.209227215695, 9315.627374904996, 9333.206677432427, 9294.535273236344, 9372.906270937272, 9401.0, 9406.811863762721, 9432.434053956751, 9387.57755900986, 9417.246620270362, 9424.717258482257, 9457.10157535033, 9465.242780577571, 9360.578773955189, 9386.20217281532, 9409.670661526881, 9426.198773104294, 9426.905730942672, 9267.212286955617, 9314.487703176323, 9343.766405839908, 9357.157855792942, 9385.249180065579, 43950.340744888854, 44208.778956105016, 44465.110697786185, 44496.77751611254, 44720.77639611813, 3648.343298206322, 3773.415120656303, 3861.7876016819064, 4413.757243351614, 4612.677112602119, 35341.293174136634, 35430.822845885865, 35511.64488355109, 35607.64392356095, 35958.10104747376, 4694.201985662446, 4696.553827386393, 4697.906041879178, 4765.373010092608, 4792.017636384533, 263610.0, 266796.0, 266910.66544667346, 267355.0, 270138.0, 65299.347006530275, 65793.0, 65797.34202657912, 65988.34011659918, 66033.6698316515, 39586.0, 40211.0, 40395.0, 42597.57402425998, 42609.573904260564, 17075.658486830194, 17136.22886970089, 17247.65504689899, 17260.654786904193, 17304.221310041077, 12979.675508112372, 13019.934900325437, 13040.413181406859, 13044.869551304555, 13057.412416441282, 21087.578248434944, 21160.894195528923, 21489.35531934013, 21602.783972160076, 21610.89194554017, 7659.808504787425, 7837.255460731221, 7883.329916957123, 7941.404394670307, 8036.4776289541, 1095.611058074372, 1096.331237944853, 1110.6279396402135, 1113.0705860606322, 1222.896053835416, 33978.66021339804, 34374.31251374959, 34490.0, 34625.653743462746, 35145.0, 1183.0831105893, 1195.8086706126978, 1202.9715788006044, 1211.9818202726967, 1241.4475558376437, 18154.18306176225, 18184.818151818312, 18229.17968691412, 18409.815901841077, 18458.81541184598, 702308.0, 705657.0, 709425.0, 709841.0, 710421.0, 67499.0, 67519.66240168866, 68011.65994169998, 68197.65901170562, 68282.65858670774, 1445.147363055802, 1454.8472410396967, 1458.7593047147313, 1486.4054378248786, 1486.6432056306408, 33182.66817331796, 33742.66257337395, 34030.48954265638, 34344.313113738084, 34523.65476345205, 58036.419635803104, 58261.0, 58508.4149158503, 58929.70535147297, 59078.70460647669, 508320.0, 509260.0, 519165.0, 521622.0, 521641.0, 31435.214119647193, 31692.524612130375, 31703.207419814687, 31925.0, 32091.35817283688, 52781.208281875035, 53803.730981344845, 54047.0, 54129.72935135299, 54186.72906635442, 43933.0, 46502.302465462366, 46689.53310466852, 46752.532474674816, 46860.29709554291, 43729.78135109304, 46369.76815116038, 46460.7676961613, 46877.29684054674, 47884.760576196895, 46117.53882461267, 46646.76676616595, 47054.76472617615, 47251.0, 48591.514084858696, 38179.61820381872, 39175.60824391834, 39593.80203098966, 40522.797386012884, 40580.0, 30259.243518911324, 30742.23144421318, 30903.0, 31212.687873120976, 31284.687153128176], "value": 21555.31469845572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.6338386103386, 407.5862999055957, 410.1121072877221, 416.98957526061844, 424.98512552060686, 19436.319728809496, 20580.10566460234, 21259.0, 21295.787042129574, 21434.24980125696, 19616.117274722637, 19670.704939425883, 20013.799862001375, 20086.59826803463, 20151.49621259469, 5868.618539794921, 6019.909701354483, 6037.882991646559, 6048.818535443944, 6072.848178795522, 5907.350191478934, 5931.288245410557, 5980.043193089106, 5997.010493268605, 6026.186464827236, 5827.883442331172, 5899.557533185007, 5938.64368137911, 5943.791967281146, 5969.761209551612, 5504.889902201933, 5737.799177028784, 5790.218320526759, 5943.465088142067, 5945.197398351233, 5637.605367624268, 5669.8582535436535, 5700.429957004308, 5706.4864162225185, 5727.169560413717, 13908.0, 13949.790753138712, 14107.36516856744, 14144.85855141456, 21273.574528509344, 2457.668214791016, 2623.4490756941063, 2689.2201261634104, 2713.470873179731, 2898.7101289871057, 9591.376560523613, 9699.854502182472, 9712.271579631555, 9952.651657192037, 10064.144547713453, 1953.6092781443767, 1981.2485435641615, 2018.2128969701828, 2033.3188381892085, 2094.476380904774, 86939.0, 90774.0, 91261.54369228178, 92278.5386073072, 93677.53161234218, 11283.887161128448, 11354.772904542027, 11371.829422558669, 11598.94200528992, 11719.707007324885, 16126.113063781604, 16233.0, 16236.837631623768, 16268.75596866048, 16279.430219942245, 7645.885311720217, 7881.408894332833, 7912.0505539335645, 8059.476134051268, 8071.556064416513, 7246.166690830517, 7278.272172782786, 7414.888776668355, 7457.925420745723, 7480.812979675552, 4805.951940480621, 4811.4707382187935, 4865.683730557503, 4866.05112003156, 4932.53140951609, 2060.9484762881048, 2080.9271675491286, 2133.0934352899953, 2206.227820262895, 2217.7449593296656, 935.4667839331652, 1045.7019749371393, 1071.2608300272786, 1107.092184408777, 1135.4720055174218, 19489.707654385194, 19601.705974410394, 19682.803171968382, 20150.59698806045, 20302.390928272176, 1020.1991436722137, 1031.3554028732042, 1036.1710631494775, 1054.3989925742253, 1120.3166068698088, 10617.0, 11023.17326200538, 11080.224384293022, 11106.611268605722, 11321.377324247082, 436725.0, 438662.0, 442321.0, 498889.0, 591782.0, 92510.53744731368, 93862.14824811261, 99016.50491747496, 102291.0, 105184.0, 1030.8659874216303, 1062.98937010632, 1064.7391389109562, 1075.887031861659, 1082.155918383662, 17024.48926532206, 17385.652286953937, 17686.646267074328, 17722.379716709853, 18185.272589096287, 44683.77658111689, 45017.54982450134, 45604.0, 46240.53759462362, 46244.306335404326, 63586.0, 67410.0, 67605.71792625643, 67802.0, 68321.0, 7958.681652734057, 7972.202779721982, 8084.797880053045, 8108.189181082081, 8693.00030496501, 11782.646520604396, 13302.534411295559, 13331.933340333237, 13348.265845378597, 13521.323933803074, 11867.584634538096, 11892.821607676065, 11893.167478276437, 12000.03999680006, 12016.75966480683, 11451.0, 11795.35125568102, 12019.278843269432, 12052.397380131133, 12082.637520874388, 11415.543378264776, 12232.75534489323, 12358.44387002569, 12392.380380981096, 12594.24434533931, 7696.230376962259, 7815.687372505264, 7840.490368126054, 7960.880586791319, 8083.0, 9734.951325243328, 11317.151213658844, 11367.0, 11426.457428247413, 11950.2829830207], "value": 9400.364235827361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.5752418085026, 1583.4616230481633, 1772.1493683032145, 1839.4481655503346, 1881.3321270948813, 8001.199880011995, 8961.148690874365, 9069.410488318255, 9621.855672164924, 9664.758381040469, 9027.95486022569, 9376.249900008, 9392.718218453458, 9417.152456278933, 9429.057094290567, 2482.689663792026, 2515.270571534262, 2533.569293220157, 2534.252395543319, 2540.5808041673104, 2425.260295609847, 2533.5439620868237, 2537.9467520978774, 2542.8855701493476, 2545.121932933135, 2479.876006199692, 2489.029278581355, 2521.9117330893423, 2534.6958364996226, 2535.936601584957, 2392.294273189413, 2397.7961873240797, 2403.903843846244, 2429.53838770633, 2459.4220358215807, 2410.457647029422, 2425.07847018132, 2429.5991161458337, 2433.707955045406, 2464.199135281023, 9585.568649410794, 9639.180669643089, 9687.170544765922, 9689.70930872075, 9737.756556086084, 5567.610267281251, 5706.914396284059, 5707.828765137053, 5708.115242137475, 5726.31284245889, 3461.16931936336, 3560.1455650643916, 3576.2132330887425, 3610.62088480711, 3638.399664055428, 1017.9287449878511, 1061.713337398898, 1127.3574062784216, 1128.9613555528852, 1138.1918837625296, 13115.737685246242, 13137.474501019948, 13178.209307441584, 13250.602481925558, 13296.468141274241, 4840.927386089212, 4856.514348565115, 4994.32576602154, 4998.375203099613, 5104.846854594368, 11080.556777728962, 11120.777584448428, 11126.276792008493, 11180.888191118147, 11195.216334856645, 5794.391588883106, 5797.246357973437, 5805.36141024487, 5807.94192058074, 5818.0, 4719.339292499053, 5095.980803839247, 5199.948000519947, 5230.424653288135, 5312.0, 4162.625363717219, 4230.788460576959, 4305.182015417061, 4327.653787696977, 4348.934765978513, 1330.3680751643085, 1336.8462626797848, 1339.2500199888093, 1374.1068305601239, 1378.3315092180285, 1009.2178561614808, 1036.533559898049, 1114.5931734916696, 1114.7157474844025, 1118.9888101119047, 6416.069669897839, 6437.452816510649, 6617.536772425884, 6623.503237257225, 6796.592204467748, 458.4796256249181, 458.8233530090872, 466.6523440037194, 472.7305435901503, 474.5491782806348, 4653.511381304914, 4708.246680531099, 4733.246745521061, 4778.617710583145, 4798.1123492154065, 10929.617463388744, 11342.489587968561, 11362.431878406047, 11378.31730096197, 11415.315081094996, 11066.446677666085, 11260.324380537195, 11275.718107047389, 11460.598879039193, 11508.88491115078, 8675.436096653824, 8791.648334066693, 8807.559622018745, 8834.425762325556, 8875.112488751074, 6407.199100112486, 6650.4347130492515, 6655.633940133145, 6741.426978706865, 6849.1096157499205, 10745.409002504937, 11066.0, 11074.833877491683, 11150.721231969266, 11219.887801122211, 11025.0, 11231.15766317513, 11236.325820450798, 11348.319100853974, 11395.031422329195, 7331.486795924234, 8544.829103418022, 8570.48577085377, 8623.56882155877, 8968.461892286483, 9089.363744537819, 9428.434293942386, 9440.244780417413, 9443.575039123394, 9455.338126331091, 8116.107228204774, 8127.349812015143, 8260.876086858581, 8275.255227029596, 8287.378446616649, 7828.452008359588, 7952.833670853216, 7994.360451163777, 8044.798880027812, 8329.791755205926, 7458.993035940296, 7460.962695186489, 7489.176190619137, 7579.279968402993, 7652.196519365387, 5321.36143662763, 5383.757730902041, 5454.59090568201, 5457.61796674229, 5511.173324001432, 5277.20841873722, 5382.327209098863, 5401.243825864303, 5440.917370920134, 5508.421615730291], "value": 4881.753357298141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.1265358225626, 321.18259030766694, 322.10454935279915, 348.4181417033554, 349.1881375801263, 5740.79907203248, 5824.90303490743, 5852.941470585293, 5861.384554621767, 5861.765529378819, 5659.405762394951, 5688.687122208287, 5714.771409143629, 5733.827985160441, 5740.397258287871, 4254.829806807724, 4328.3074708046715, 4454.821807127711, 4466.0, 4467.865964021084, 4449.377087207794, 4450.977745111286, 4451.821927122911, 4470.8882277942985, 4473.284274516078, 4440.7335559866515, 4444.73331600105, 4456.732596044248, 4460.30865215892, 4467.687261891669, 4321.567843215685, 4453.888652783674, 4476.127155204753, 4480.372747815309, 4491.528389519086, 3972.781497017663, 3991.7604943703473, 3996.140829721595, 4000.599940006005, 4010.8796736097966, 5578.748956296977, 5596.608237423382, 5601.579881508903, 5603.971980140073, 5615.634983726045, 4983.401991761012, 5115.897682046376, 5152.407473140599, 5153.994970980661, 5164.225366195101, 5958.940410595926, 5969.492593129589, 5979.0, 5981.8634459453, 5993.790217342415, 4372.360364863176, 4378.6934914555995, 4386.71486353386, 4391.077873646538, 4392.363107349448, 5960.195373624549, 6151.6001459904965, 6247.250329960389, 6254.843628909268, 6256.217972753406, 4234.597713217239, 4283.657307415461, 4296.2911119664905, 4297.431437525343, 4298.140371925628, 4225.576011684208, 4324.329728892027, 4332.415123958288, 4347.891302717394, 4357.498887627963, 4912.533309335608, 4951.653384263139, 4996.6502344836235, 5022.96110852936, 5026.004326560394, 6023.247094113236, 6024.186734790835, 6033.788817391458, 6054.182685337511, 6071.787487437919, 1524.8475152484884, 1549.403479660339, 1559.033399292446, 1561.4691005058107, 1568.749000159969, 991.1624677147798, 1015.3298822776932, 1029.552144816992, 1038.5638032026568, 1054.5201933120566, 5743.741531631086, 5808.564357673192, 5820.476157145878, 5824.242848429762, 5863.20846685692, 6092.75628974836, 6168.599041062316, 6202.379762023852, 6229.813105606839, 6246.906296405557, 5680.005998950167, 5893.615000474885, 5909.734061967221, 5971.313298970587, 5997.880042399128, 3275.9344813103603, 3323.4516304809918, 3339.780979942352, 3342.5821772278464, 3358.7313014958745, 6241.751649670085, 6384.585001974857, 6424.453921416732, 6455.870882582322, 6463.2244130703675, 6288.4593274648005, 6323.747050118036, 6326.6520341381665, 6328.367163283635, 6340.1440805490665, 6123.571350005457, 6374.521910856704, 6411.0383442484, 6437.746788623944, 6473.546851720429, 6186.010238362021, 6267.114389078801, 6277.905831412624, 6284.74861005573, 6293.213348331458, 6174.536909731878, 6175.0, 6184.474319682878, 6207.410296021871, 6232.875342493216, 6290.46531044848, 6363.245685045845, 6454.322296159024, 6479.509712765996, 6480.898247298065, 4396.978015109904, 4408.162449134654, 4425.955740442683, 4438.7114837535455, 4440.400545926259, 6045.818625441244, 6188.133661287513, 6271.96864015677, 6275.435210830865, 6301.747930082745, 6077.2099627049965, 6083.361247068994, 6093.29927058394, 6103.725332359965, 6139.171211886517, 6082.482988945812, 6096.695165241809, 6109.991851344656, 6118.0, 6120.0, 5825.76696932118, 5851.327097383685, 5896.852578685567, 5985.940140598713, 6015.015045035216, 6104.298005729398, 6120.234970628671, 6123.0, 6143.877122457615, 6169.96915015422, 5809.2738407699035, 5965.761369545343, 5970.164177015135, 5987.730552125077, 5999.5800293979], "value": 4596.383861599046, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.8342123022321, 785.8781888807237, 826.6156237349632, 840.0675250471979, 943.5989704375638, 16148.273327700252, 17087.23107460164, 17342.47972560822, 17352.65294694107, 17375.218115184816, 15635.84364156358, 16255.674886502293, 16402.0, 16932.915335423306, 17142.485725428258, 871.6034204436982, 890.8262888736699, 890.9376343655947, 891.1578558262445, 891.393852180517, 865.1132589096164, 898.128815049401, 900.6262401103534, 903.1194585279366, 903.4895284164459, 927.0081013315755, 929.8000929800091, 933.2906990687059, 934.028610245344, 936.4615346175942, 921.9812107621116, 926.8656044873455, 930.1721467893581, 931.3573634192386, 931.3852857114306, 683.9726410943606, 689.0938415983017, 689.689639662149, 689.8620275944832, 690.927452617478, 16323.0, 16408.26162822676, 16441.0, 16455.177241138015, 16535.925164864246, 16407.67184656312, 16641.583960400967, 16712.24794884233, 16721.749173762404, 16819.83180168195, 13036.608901732963, 13078.280694561796, 13236.272005039718, 13288.933555332258, 13464.798028029587, 4210.768407737573, 4267.338562522814, 4272.743635381887, 4371.103923695635, 4415.8233670653135, 88820.55589722075, 88833.99999999936, 89025.55487222587, 90132.54933725289, 90584.0, 11923.403829808649, 12369.196002260005, 12747.107702460924, 12808.295543745184, 12862.0, 25842.095526656478, 26139.73860261411, 26181.607275890878, 26610.201693949213, 26718.0, 8816.471011739317, 8876.289896808239, 8930.151635594739, 8989.325800564984, 9145.679901203426, 4601.7699115044115, 4671.135839869635, 4682.180618391768, 4711.434627844681, 4712.034033023222, 3801.429785532137, 3937.995811068159, 3969.563348031714, 3974.364101743707, 3985.880423587297, 668.204836244867, 670.577536152221, 671.8656268746271, 681.4582406986458, 688.8484533402643, 653.3531803514486, 737.5611511150856, 757.3184134279193, 761.2425636491615, 773.555205756681, 10007.14939230159, 10041.899581004243, 10210.897891021143, 10211.540480678388, 10790.56837726482, 682.6654939079815, 688.1295161620554, 703.426707233603, 717.0606505477841, 723.9312265334785, 4616.403367704051, 4860.052289803454, 4869.464358920516, 4885.340479035285, 4899.608031357483, 129918.0, 133733.3313333427, 137397.3130134343, 139571.30214348863, 140441.0, 114205.0, 119557.0, 120076.0, 121572.99999999822, 123235.3838230821, 60068.699656501434, 102537.0, 103851.4807425958, 105266.47366763117, 106145.4692726547, 9223.723288301362, 9247.260219182584, 9346.597580657271, 9473.715788526355, 9579.521023948913, 49241.0, 58419.70790146022, 58865.70567147137, 59754.103688443836, 60651.0, 49438.258426124354, 66311.66844165749, 75389.6230518844, 75954.24045759471, 80104.0, 13781.655458613615, 13895.305234738425, 14055.22696251716, 14102.0, 14209.644758881112, 16687.833121668627, 17006.404775832787, 17062.744058838878, 17100.91449542744, 17401.21694523775, 14090.718185636024, 14335.808683455343, 14640.70718585601, 15498.0, 16741.414050508174, 14332.57002289933, 14410.42358305656, 14795.482158124414, 14895.702085958437, 15011.474598389004, 13842.723145537235, 14009.43962241499, 14700.264986750833, 14741.484048058266, 14765.187914664797, 11126.777464450828, 11220.607278745205, 11933.28400295985, 12021.819672704742, 12509.0, 12460.439280232593, 12562.309073000708, 12593.811092833432, 12686.555970540987, 12929.418176181905], "value": 8244.6507802626, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 110.0, 106.0, 110.0, 110.0, 110.0, 110.0, 112.0, 122.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 114.0, 140.0, 174.0, 122.0, 140.0, 143.0, 119.0, 188.0, 118.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 162.0, 161.0, 160.0, 161.0, 160.0, 162.0, 163.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 167.0, 75.0, 75.0, 76.0, 74.0, 168.0, 77.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 150.0, 83.0, 84.0, 86.0, 147.0, 83.0, 82.0, 141.0], "value": 102.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 153.0, 146.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 54.0, 53.0, 53.0, 60.0, 54.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 59.0, 63.0, 59.0, 59.0, 59.0, 59.0, 61.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 72.0, 83.0, 74.0, 77.0, 72.0, 78.0, 72.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 120.0, 117.0, 127.0, 121.0, 116.0, 129.0, 156.0, 123.0], "value": 125.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 153.0, 149.0, 158.0, 154.0, 153.0, 151.0, 151.0, 151.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 63.0, 65.0, 61.0, 64.0, 61.0, 62.0, 68.0, 60.0], "value": 63.6, "unit": "score"}]}]} 15:14:05 INFO - Return code: 0 15:14:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:14:05 ERROR - # TBPL SUCCESS # 15:14:05 INFO - Running post-action listener: _resource_record_post_action 15:14:05 INFO - [mozharness: 2016-09-16 22:14:05.124991Z] Finished run-tests step (success) 15:14:05 INFO - Running post-run listener: _resource_record_post_run 15:14:05 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 324902912; Read time: 36; Write time: 580520 15:14:05 INFO - TinderboxPrint: CPU usage
13.3% 15:14:05 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 36 15:14:05 INFO - TinderboxPrint: I/O write bytes / time
324,902,912 / 580,520 15:14:05 INFO - TinderboxPrint: CPU idle
8,249.2 (86.7%) 15:14:05 INFO - TinderboxPrint: CPU user
1,196.7 (12.6%) 15:14:05 INFO - TinderboxPrint: Swap in / out
0 / 0 15:14:05 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 78688256; Read time: 0; Write time: 226240 15:14:05 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 245600256; Read time: 36; Write time: 354280 15:14:05 INFO - Running post-run listener: _upload_blobber_files 15:14:05 INFO - Blob upload gear active. 15:14:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:14:05 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 15:14:05 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:14:05 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:14:06 INFO - (blobuploader) - INFO - Open directory for files ... 15:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:14:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:06 INFO - (blobuploader) - INFO - Done attempting. 15:14:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:14:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:14:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:08 INFO - (blobuploader) - INFO - Done attempting. 15:14:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:14:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:14:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:14:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:14:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:14:13 INFO - (blobuploader) - INFO - Done attempting. 15:14:13 INFO - (blobuploader) - INFO - Iteration through files over. 15:14:13 INFO - Return code: 0 15:14:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:14:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:14:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1536d6b45f937ab20ddf9d80b8993d90ce32b727598d63db76e258fc98d4bab9b5f4849769f6527ddd5c30584a635dbe7ab7493d7893c4044815b6bd729f020d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85663ecd8f40744ad54a6d0ff8fd74c21da7dada09a892a137e9e3c518f03817755efdcb4bc4188df9cd5c6890d2a4807395d0ae276b37347d02ec4f06c37f8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afc1a2d91711743c4eef65842ac7de5ac3654c60f96649d074cfdb1b82dc6dd516b3ed914c8ca7341bbb2da1a90dbf3258845613dc239f4f0974972e0f9a707"} 15:14:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:14:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:14:13 INFO - Contents: 15:14:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1536d6b45f937ab20ddf9d80b8993d90ce32b727598d63db76e258fc98d4bab9b5f4849769f6527ddd5c30584a635dbe7ab7493d7893c4044815b6bd729f020d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85663ecd8f40744ad54a6d0ff8fd74c21da7dada09a892a137e9e3c518f03817755efdcb4bc4188df9cd5c6890d2a4807395d0ae276b37347d02ec4f06c37f8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afc1a2d91711743c4eef65842ac7de5ac3654c60f96649d074cfdb1b82dc6dd516b3ed914c8ca7341bbb2da1a90dbf3258845613dc239f4f0974972e0f9a707"} 15:14:13 INFO - Running post-run listener: copy_logs_to_upload_dir 15:14:13 INFO - Copying logs to upload dir... 15:14:13 INFO - mkdir: /builds/slave/test/build/upload/logs 15:14:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.843884 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-09-16 15:14:13.190490) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 15:14:13.194049) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1536d6b45f937ab20ddf9d80b8993d90ce32b727598d63db76e258fc98d4bab9b5f4849769f6527ddd5c30584a635dbe7ab7493d7893c4044815b6bd729f020d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85663ecd8f40744ad54a6d0ff8fd74c21da7dada09a892a137e9e3c518f03817755efdcb4bc4188df9cd5c6890d2a4807395d0ae276b37347d02ec4f06c37f8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afc1a2d91711743c4eef65842ac7de5ac3654c60f96649d074cfdb1b82dc6dd516b3ed914c8ca7341bbb2da1a90dbf3258845613dc239f4f0974972e0f9a707"} build_url:https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011454 build_url: 'https://queue.taskcluster.net/v1/task/Jx6lf_ZASk-Av9eDc_U3Ow/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1536d6b45f937ab20ddf9d80b8993d90ce32b727598d63db76e258fc98d4bab9b5f4849769f6527ddd5c30584a635dbe7ab7493d7893c4044815b6bd729f020d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85663ecd8f40744ad54a6d0ff8fd74c21da7dada09a892a137e9e3c518f03817755efdcb4bc4188df9cd5c6890d2a4807395d0ae276b37347d02ec4f06c37f8a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afc1a2d91711743c4eef65842ac7de5ac3654c60f96649d074cfdb1b82dc6dd516b3ed914c8ca7341bbb2da1a90dbf3258845613dc239f4f0974972e0f9a707"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 15:14:13.218871) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:14:13.219174) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474062369.625233-877254937 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004474 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 15:14:13.303710) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 15:14:13.304031) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 15:14:13.304415) ========= ========= Total master_lag: 0.16 =========