builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-058 starttime: 1472919767.54 results: success (0) buildid: 20160903062049 builduid: e64820c2ff7a4cd79d47abdf96da75a8 revision: 7f2f0754a1261a89164216070ae36e839e38d39b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.544057) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.544670) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.544973) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.594786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.595093) ========= 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=0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-03 09:22:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.8M=0s 2016-09-03 09:22:48 (40.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.372876 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.994415) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:47.994694) ========= 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=0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020442 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.052119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.052479) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7f2f0754a1261a89164216070ae36e839e38d39b --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 7f2f0754a1261a89164216070ae36e839e38d39b --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=0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-03 09:22:49,041 truncating revision to first 12 chars 2016-09-03 09:22:49,041 Setting DEBUG logging. 2016-09-03 09:22:49,041 attempt 1/10 2016-09-03 09:22:49,041 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7f2f0754a126?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 09:22:49,447 unpacking tar archive at: mozilla-aurora-7f2f0754a126/testing/mozharness/ program finished with exit code 0 elapsedTime=0.731355 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.798684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.799081) ========= script_repo_revision: 7f2f0754a1261a89164216070ae36e839e38d39b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.799543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.799835) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 09:22:48.814012) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-03 09:22:48.814382) ========= /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=0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:22:49 INFO - MultiFileLogger online at 20160903 09:22:49 in /builds/slave/test 09:22:49 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 09:22:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:22:49 INFO - {'append_to_log': False, 09:22:49 INFO - 'base_work_dir': '/builds/slave/test', 09:22:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:22:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:22:49 INFO - 'branch': 'Mozilla-Aurora', 09:22:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:22:49 INFO - 'config_files': ('talos/linux_config.py',), 09:22:49 INFO - 'default_actions': ('clobber', 09:22:49 INFO - 'read-buildbot-config', 09:22:49 INFO - 'download-and-extract', 09:22:49 INFO - 'populate-webroot', 09:22:49 INFO - 'create-virtualenv', 09:22:49 INFO - 'install', 09:22:49 INFO - 'run-tests'), 09:22:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:22:49 INFO - 'download_minidump_stackwalk': True, 09:22:49 INFO - 'download_symbols': 'ondemand', 09:22:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:22:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:22:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:22:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:22:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:22:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:22:49 INFO - 'installer_path': 'installer.exe', 09:22:49 INFO - 'log_level': 'info', 09:22:49 INFO - 'log_name': 'talos', 09:22:49 INFO - 'log_to_console': True, 09:22:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:22:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:22:49 INFO - 'opt_config_files': (), 09:22:49 INFO - 'pip_index': False, 09:22:49 INFO - 'sps_profile': False, 09:22:49 INFO - 'sps_profile_interval': 0, 09:22:49 INFO - 'suite': 'dromaeojs-e10s', 09:22:49 INFO - 'system_bits': '32', 09:22:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:22:49 INFO - 'title': 'talos-linux64-ix-058', 09:22:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:22:49 INFO - 'use_talos_json': True, 09:22:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:22:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:22:49 INFO - 'work_dir': 'build'} 09:22:49 INFO - [mozharness: 2016-09-03 16:22:49.888750Z] Running clobber step. 09:22:49 INFO - Running pre-action listener: _resource_record_pre_action 09:22:49 INFO - Running main action method: clobber 09:22:49 INFO - rmtree: /builds/slave/test/build 09:22:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:22:50 INFO - Running post-action listener: _resource_record_post_action 09:22:50 INFO - [mozharness: 2016-09-03 16:22:50.360420Z] Finished clobber step (success) 09:22:50 INFO - [mozharness: 2016-09-03 16:22:50.360521Z] Running read-buildbot-config step. 09:22:50 INFO - Running pre-action listener: _resource_record_pre_action 09:22:50 INFO - Running main action method: read_buildbot_config 09:22:50 INFO - Using buildbot properties: 09:22:50 INFO - { 09:22:50 INFO - "project": "", 09:22:50 INFO - "product": "firefox", 09:22:50 INFO - "script_repo_revision": "production", 09:22:50 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 09:22:50 INFO - "repository": "", 09:22:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 09:22:50 INFO - "buildid": "20160903062049", 09:22:50 INFO - "pgo_build": "True", 09:22:50 INFO - "basedir": "/builds/slave/test", 09:22:50 INFO - "buildnumber": 484, 09:22:50 INFO - "slavename": "talos-linux64-ix-058", 09:22:50 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:22:50 INFO - "platform": "ubuntu64_hw", 09:22:50 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:22:50 INFO - "branch": "mozilla-aurora", 09:22:50 INFO - "repo_path": "releases/mozilla-aurora", 09:22:50 INFO - "revision": "7f2f0754a1261a89164216070ae36e839e38d39b", 09:22:50 INFO - "stage_platform": "linux64-pgo", 09:22:50 INFO - "builduid": "e64820c2ff7a4cd79d47abdf96da75a8", 09:22:50 INFO - "slavebuilddir": "test" 09:22:50 INFO - } 09:22:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 09:22:50 INFO - Running post-action listener: _resource_record_post_action 09:22:50 INFO - [mozharness: 2016-09-03 16:22:50.362996Z] Finished read-buildbot-config step (success) 09:22:50 INFO - [mozharness: 2016-09-03 16:22:50.363085Z] Running download-and-extract step. 09:22:50 INFO - Running pre-action listener: _resource_record_pre_action 09:22:50 INFO - Running main action method: download_and_extract 09:22:50 INFO - mkdir: /builds/slave/test/build/tests 09:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:22:50 INFO - https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:22:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:22:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:22:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:22:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:22:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:22:50 INFO - retry: Failed, sleeping 30 seconds before retrying 09:23:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:23:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:23:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:23:20 INFO - retry: Failed, sleeping 60 seconds before retrying 09:24:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:24:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:24:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:24:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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! 09:24:20 INFO - Caught exception: HTTP Error 404: Not Found 09:24:20 INFO - Caught exception: HTTP Error 404: Not Found 09:24:20 INFO - Caught exception: HTTP Error 404: Not Found 09:24:20 INFO - trying https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:24:20 INFO - Downloading https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:24:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:24:21 INFO - Downloaded 1361 bytes. 09:24:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 09:24:21 INFO - Using the following test package requirements: 09:24:21 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 09:24:21 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:24:21 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:24:21 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'jsshell-linux-x86_64.zip'], 09:24:21 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:24:21 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 09:24:21 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:24:21 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:24:21 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:24:21 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 09:24:21 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:24:21 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:24:21 INFO - https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:24:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:24:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:24:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:24:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:24:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:24:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:24:21 INFO - retry: Failed, sleeping 30 seconds before retrying 09:24:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:24:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:24:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:24:51 INFO - retry: Failed, sleeping 60 seconds before retrying 09:25:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:25:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:25:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:25:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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! 09:25:51 INFO - Caught exception: HTTP Error 404: Not Found 09:25:51 INFO - Caught exception: HTTP Error 404: Not Found 09:25:51 INFO - Caught exception: HTTP Error 404: Not Found 09:25:51 INFO - trying https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:25:51 INFO - Downloading https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:25:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:25:51 INFO - Downloaded 21770394 bytes. 09:25:51 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'] 09:25:51 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 09:25:53 INFO - Return code: 0 09:25:53 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 09:25: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')]} 09:25:53 INFO - https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:25:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:25:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:25:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:25:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:25:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:25:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:25:53 INFO - retry: Failed, sleeping 30 seconds before retrying 09:26:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:26:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:26:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:26:23 INFO - retry: Failed, sleeping 60 seconds before retrying 09:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:27:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:27:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:27:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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! 09:27:23 INFO - Caught exception: HTTP Error 404: Not Found 09:27:23 INFO - Caught exception: HTTP Error 404: Not Found 09:27:23 INFO - Caught exception: HTTP Error 404: Not Found 09:27:23 INFO - trying https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 09:27:23 INFO - Downloading https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:27:24 INFO - Downloaded 21770394 bytes. 09:27:24 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'] 09:27:24 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 09:27:25 INFO - Return code: 0 09:27:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:27:25 INFO - https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:27:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:27:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:27:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:27:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:27:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:27:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:27:25 INFO - retry: Failed, sleeping 30 seconds before retrying 09:27:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:27:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:27:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:27:55 INFO - retry: Failed, sleeping 60 seconds before retrying 09:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:28:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:28:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:28:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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! 09:28:55 INFO - Caught exception: HTTP Error 404: Not Found 09:28:55 INFO - Caught exception: HTTP Error 404: Not Found 09:28:55 INFO - Caught exception: HTTP Error 404: Not Found 09:28:55 INFO - trying https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 09:28:55 INFO - Downloading https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:28:56 INFO - Downloaded 14413740 bytes. 09:28:56 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'] 09:28:56 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 09:28:56 INFO - Return code: 0 09:28: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')]} 09:28:56 INFO - https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:28:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:28:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:28:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:28:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:28:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:28:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 09:28:56 INFO - retry: Failed, sleeping 30 seconds before retrying 09:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:29:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:29:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 09:29:26 INFO - retry: Failed, sleeping 60 seconds before retrying 09:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:30:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:30:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 09:30:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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! 09:30:26 INFO - Caught exception: HTTP Error 404: Not Found 09:30:26 INFO - Caught exception: HTTP Error 404: Not Found 09:30:26 INFO - Caught exception: HTTP Error 404: Not Found 09:30:26 INFO - trying https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:30:26 INFO - Downloading https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:30:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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 09:30:28 INFO - Downloaded 63896209 bytes. 09:30:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:30:28 INFO - mkdir: /builds/slave/test/properties 09:30:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:28 INFO - Contents: 09:30:28 INFO - build_url:https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 09:30:29 INFO - Running post-action listener: _resource_record_post_action 09:30:29 INFO - Running post-action listener: set_extra_try_arguments 09:30:29 INFO - [mozharness: 2016-09-03 16:30:29.142385Z] Finished download-and-extract step (success) 09:30:29 INFO - [mozharness: 2016-09-03 16:30:29.142619Z] Running populate-webroot step. 09:30:29 INFO - Running pre-action listener: _resource_record_pre_action 09:30:29 INFO - Running main action method: populate_webroot 09:30:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:29 INFO - '--tppagecycles', 09:30:29 INFO - '7'], 09:30:29 INFO - 'tests': ['tsvgm']}}, 09:30:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['damp', 'tps']}, 09:30:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'tests': ['damp', 'tps']}, 09:30:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['dromaeo_dom']}, 09:30:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['basic_compositor_video']}, 09:30:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:30:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['a11yr', 09:30:29 INFO - 'ts_paint', 09:30:29 INFO - 'tpaint', 09:30:29 INFO - 'sessionrestore', 09:30:29 INFO - 'sessionrestore_no_auto_restore', 09:30:29 INFO - 'tabpaint']}, 09:30:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:29 INFO - 'ts_paint', 09:30:29 INFO - 'tpaint', 09:30:29 INFO - 'sessionrestore', 09:30:29 INFO - 'sessionrestore_no_auto_restore', 09:30:29 INFO - 'tabpaint']}, 09:30:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:29 INFO - 'ts_paint', 09:30:29 INFO - 'tpaint', 09:30:29 INFO - 'sessionrestore', 09:30:29 INFO - 'sessionrestore_no_auto_restore', 09:30:29 INFO - 'tabpaint']}, 09:30:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:29 INFO - 'ts_paint', 09:30:29 INFO - 'tpaint', 09:30:29 INFO - 'sessionrestore', 09:30:29 INFO - 'sessionrestore_no_auto_restore', 09:30:29 INFO - 'tabpaint']}, 09:30:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['a11yr', 09:30:29 INFO - 'ts_paint', 09:30:29 INFO - 'tpaint', 09:30:29 INFO - 'sessionrestore', 09:30:29 INFO - 'sessionrestore_no_auto_restore', 09:30:29 INFO - 'tabpaint']}, 09:30:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['a11yr', 09:30:29 INFO - 'ts_paint', 09:30:29 INFO - 'tpaint', 09:30:29 INFO - 'sessionrestore', 09:30:29 INFO - 'sessionrestore_no_auto_restore', 09:30:29 INFO - 'tabpaint']}, 09:30:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['tsvgx', 09:30:29 INFO - 'tsvgr_opacity', 09:30:29 INFO - 'tart', 09:30:29 INFO - 'tscrollx', 09:30:29 INFO - 'cart']}, 09:30:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:29 INFO - 'tsvgr_opacity', 09:30:29 INFO - 'tart', 09:30:29 INFO - 'tscrollx', 09:30:29 INFO - 'cart']}, 09:30:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'talos_options': ['--disable-e10s'], 09:30:29 INFO - 'tests': ['tp5o']}, 09:30:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'tests': ['tp5o']}, 09:30:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'talos_options': ['--disable-e10s', 09:30:29 INFO - '--xperf_path', 09:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:29 INFO - 'tests': ['tp5n']}, 09:30:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:29 INFO - 'talos_options': ['--xperf_path', 09:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:29 INFO - 'tests': ['tp5n']}}, 09:30:29 INFO - 'talos.zip': {'path': '', 09:30:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:29 INFO - Running post-action listener: _resource_record_post_action 09:30:29 INFO - [mozharness: 2016-09-03 16:30:29.158324Z] Finished populate-webroot step (success) 09:30:29 INFO - [mozharness: 2016-09-03 16:30:29.158413Z] Running create-virtualenv step. 09:30:29 INFO - Running pre-action listener: _resource_record_pre_action 09:30:29 INFO - Running main action method: create_virtualenv 09:30:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:30:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:29 INFO - Using real prefix '/usr' 09:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:30:29 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:31 INFO - Installing pip.................done. 09:30:31 INFO - Return code: 0 09:30:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:30:31 INFO - Using env: {'DISPLAY': ':0', 09:30:31 INFO - 'HOME': '/home/cltbld', 09:30:31 INFO - 'LANG': 'en_US.UTF-8', 09:30:31 INFO - 'LANGUAGE': 'en_US:en', 09:30:31 INFO - 'LOGNAME': 'cltbld', 09:30:31 INFO - 'MAIL': '/var/mail/cltbld', 09:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:31 INFO - 'MOZ_NO_REMOTE': '1', 09:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:31 INFO - 'NO_EM_RESTART': '1', 09:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:31 INFO - 'PWD': '/builds/slave/test', 09:30:31 INFO - 'SHELL': '/bin/bash', 09:30:31 INFO - 'SHLVL': '1', 09:30:31 INFO - 'TERM': 'linux', 09:30:31 INFO - 'TMOUT': '86400', 09:30:31 INFO - 'USER': 'cltbld', 09:30:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:31 INFO - '_': '/tools/buildbot/bin/python'} 09:30:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:30:31 INFO - Cleaning up... 09:30:31 INFO - Return code: 0 09:30:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:30:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:30:31 INFO - Using env: {'DISPLAY': ':0', 09:30:31 INFO - 'HOME': '/home/cltbld', 09:30:31 INFO - 'LANG': 'en_US.UTF-8', 09:30:31 INFO - 'LANGUAGE': 'en_US:en', 09:30:31 INFO - 'LOGNAME': 'cltbld', 09:30:31 INFO - 'MAIL': '/var/mail/cltbld', 09:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:31 INFO - 'MOZ_NO_REMOTE': '1', 09:30:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:31 INFO - 'NO_EM_RESTART': '1', 09:30:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:31 INFO - 'PWD': '/builds/slave/test', 09:30:31 INFO - 'SHELL': '/bin/bash', 09:30:31 INFO - 'SHLVL': '1', 09:30:31 INFO - 'TERM': 'linux', 09:30:31 INFO - 'TMOUT': '86400', 09:30:31 INFO - 'USER': 'cltbld', 09:30:31 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:31 INFO - '_': '/tools/buildbot/bin/python'} 09:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:32 INFO - Downloading/unpacking psutil>=3.1.1 09:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:34 INFO - Installing collected packages: psutil 09:30:34 INFO - Running setup.py install for psutil 09:30:34 INFO - building 'psutil._psutil_linux' extension 09:30:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:30:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:30:34 INFO - building 'psutil._psutil_posix' extension 09:30:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:30:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:34 INFO - Successfully installed psutil 09:30:34 INFO - Cleaning up... 09:30:34 INFO - Return code: 0 09:30:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:30:34 INFO - Using env: {'DISPLAY': ':0', 09:30:34 INFO - 'HOME': '/home/cltbld', 09:30:34 INFO - 'LANG': 'en_US.UTF-8', 09:30:34 INFO - 'LANGUAGE': 'en_US:en', 09:30:34 INFO - 'LOGNAME': 'cltbld', 09:30:34 INFO - 'MAIL': '/var/mail/cltbld', 09:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:34 INFO - 'MOZ_NO_REMOTE': '1', 09:30:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:34 INFO - 'NO_EM_RESTART': '1', 09:30:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:34 INFO - 'PWD': '/builds/slave/test', 09:30:34 INFO - 'SHELL': '/bin/bash', 09:30:34 INFO - 'SHLVL': '1', 09:30:34 INFO - 'TERM': 'linux', 09:30:34 INFO - 'TMOUT': '86400', 09:30:34 INFO - 'USER': 'cltbld', 09:30:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:34 INFO - '_': '/tools/buildbot/bin/python'} 09:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:30:34 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 09:30:34 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 09:30:34 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 09:30:34 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 09:30:34 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 09:30:34 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 09:30:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:30:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:30:36 INFO - Installing collected packages: mozsystemmonitor 09:30:36 INFO - Running setup.py install for mozsystemmonitor 09:30:36 INFO - Successfully installed mozsystemmonitor 09:30:36 INFO - Cleaning up... 09:30:37 INFO - Return code: 0 09:30:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:30:37 INFO - Using env: {'DISPLAY': ':0', 09:30:37 INFO - 'HOME': '/home/cltbld', 09:30:37 INFO - 'LANG': 'en_US.UTF-8', 09:30:37 INFO - 'LANGUAGE': 'en_US:en', 09:30:37 INFO - 'LOGNAME': 'cltbld', 09:30:37 INFO - 'MAIL': '/var/mail/cltbld', 09:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:37 INFO - 'MOZ_NO_REMOTE': '1', 09:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:37 INFO - 'NO_EM_RESTART': '1', 09:30:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:37 INFO - 'PWD': '/builds/slave/test', 09:30:37 INFO - 'SHELL': '/bin/bash', 09:30:37 INFO - 'SHLVL': '1', 09:30:37 INFO - 'TERM': 'linux', 09:30:37 INFO - 'TMOUT': '86400', 09:30:37 INFO - 'USER': 'cltbld', 09:30:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:37 INFO - '_': '/tools/buildbot/bin/python'} 09:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:37 INFO - Downloading/unpacking jsonschema==2.5.1 09:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:39 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:39 INFO - warning: no files found matching '*.txt' 09:30:39 INFO - no previously-included directories found matching 'build' 09:30:39 INFO - no previously-included directories found matching 'dist' 09:30:39 INFO - no previously-included directories found matching '.git*' 09:30:39 INFO - Installing collected packages: jsonschema, functools32 09:30:39 INFO - Running setup.py install for functools32 09:30:39 INFO - warning: no files found matching '*.txt' 09:30:39 INFO - no previously-included directories found matching 'build' 09:30:39 INFO - no previously-included directories found matching 'dist' 09:30:39 INFO - no previously-included directories found matching '.git*' 09:30:39 INFO - Successfully installed jsonschema functools32 09:30:39 INFO - Cleaning up... 09:30:39 INFO - Return code: 0 09:30:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:30:39 INFO - Using env: {'DISPLAY': ':0', 09:30:39 INFO - 'HOME': '/home/cltbld', 09:30:39 INFO - 'LANG': 'en_US.UTF-8', 09:30:39 INFO - 'LANGUAGE': 'en_US:en', 09:30:39 INFO - 'LOGNAME': 'cltbld', 09:30:39 INFO - 'MAIL': '/var/mail/cltbld', 09:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:39 INFO - 'MOZ_NO_REMOTE': '1', 09:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:39 INFO - 'NO_EM_RESTART': '1', 09:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:39 INFO - 'PWD': '/builds/slave/test', 09:30:39 INFO - 'SHELL': '/bin/bash', 09:30:39 INFO - 'SHLVL': '1', 09:30:39 INFO - 'TERM': 'linux', 09:30:39 INFO - 'TMOUT': '86400', 09:30:39 INFO - 'USER': 'cltbld', 09:30:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:39 INFO - '_': '/tools/buildbot/bin/python'} 09:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:30:39 INFO - Cleaning up... 09:30:39 INFO - Return code: 0 09:30:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:30:39 INFO - Using env: {'DISPLAY': ':0', 09:30:39 INFO - 'HOME': '/home/cltbld', 09:30:39 INFO - 'LANG': 'en_US.UTF-8', 09:30:39 INFO - 'LANGUAGE': 'en_US:en', 09:30:39 INFO - 'LOGNAME': 'cltbld', 09:30:39 INFO - 'MAIL': '/var/mail/cltbld', 09:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:39 INFO - 'MOZ_NO_REMOTE': '1', 09:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:39 INFO - 'NO_EM_RESTART': '1', 09:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:39 INFO - 'PWD': '/builds/slave/test', 09:30:39 INFO - 'SHELL': '/bin/bash', 09:30:39 INFO - 'SHLVL': '1', 09:30:39 INFO - 'TERM': 'linux', 09:30:39 INFO - 'TMOUT': '86400', 09:30:39 INFO - 'USER': 'cltbld', 09:30:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:39 INFO - '_': '/tools/buildbot/bin/python'} 09:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:40 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:41 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 09:30:41 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 09:30:41 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 09:30:41 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 09:30:41 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 09:30:41 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 09:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:30:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:42 INFO - Downloading docopt-0.6.1.tar.gz 09:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:42 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:42 INFO - Running setup.py install for blobuploader 09:30:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:30:42 INFO - Running setup.py install for requests 09:30:43 INFO - Running setup.py install for docopt 09:30:43 INFO - Successfully installed blobuploader requests docopt 09:30:43 INFO - Cleaning up... 09:30:43 INFO - Return code: 0 09:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:43 INFO - Using env: {'DISPLAY': ':0', 09:30:43 INFO - 'HOME': '/home/cltbld', 09:30:43 INFO - 'LANG': 'en_US.UTF-8', 09:30:43 INFO - 'LANGUAGE': 'en_US:en', 09:30:43 INFO - 'LOGNAME': 'cltbld', 09:30:43 INFO - 'MAIL': '/var/mail/cltbld', 09:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:43 INFO - 'MOZ_NO_REMOTE': '1', 09:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:43 INFO - 'NO_EM_RESTART': '1', 09:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:43 INFO - 'PWD': '/builds/slave/test', 09:30:43 INFO - 'SHELL': '/bin/bash', 09:30:43 INFO - 'SHLVL': '1', 09:30:43 INFO - 'TERM': 'linux', 09:30:43 INFO - 'TMOUT': '86400', 09:30:43 INFO - 'USER': 'cltbld', 09:30:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:43 INFO - '_': '/tools/buildbot/bin/python'} 09:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:43 INFO - Running setup.py (path:/tmp/pip-t5Tf6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:43 INFO - Running setup.py (path:/tmp/pip-sEFXXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:43 INFO - Running setup.py (path:/tmp/pip-Yh3dt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:43 INFO - Running setup.py (path:/tmp/pip-85ncvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:43 INFO - Running setup.py (path:/tmp/pip-W9SVyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:43 INFO - Running setup.py (path:/tmp/pip-t7hZeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:43 INFO - Running setup.py (path:/tmp/pip-T0ehip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:43 INFO - Running setup.py (path:/tmp/pip-ZPQCO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:44 INFO - Running setup.py (path:/tmp/pip-ETlpho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:44 INFO - Running setup.py (path:/tmp/pip-8sYLRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:44 INFO - Running setup.py (path:/tmp/pip-PzxWwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:44 INFO - Running setup.py (path:/tmp/pip-d_44db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:44 INFO - Running setup.py (path:/tmp/pip-TNTSsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:44 INFO - Running setup.py (path:/tmp/pip-Xuk38E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:44 INFO - Running setup.py (path:/tmp/pip-R5HUgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:44 INFO - Running setup.py (path:/tmp/pip-9cP9Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:44 INFO - Running setup.py (path:/tmp/pip-iID_yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:44 INFO - Running setup.py install for manifestparser 09:30:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:30:44 INFO - Running setup.py install for mozcrash 09:30:44 INFO - Running setup.py install for mozdebug 09:30:45 INFO - Running setup.py install for mozdevice 09:30:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Running setup.py install for mozfile 09:30:45 INFO - Running setup.py install for mozhttpd 09:30:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Running setup.py install for mozinfo 09:30:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Running setup.py install for mozInstall 09:30:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Running setup.py install for mozleak 09:30:45 INFO - Running setup.py install for mozlog 09:30:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Running setup.py install for moznetwork 09:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:30:45 INFO - Running setup.py install for mozprocess 09:30:45 INFO - Running setup.py install for mozprofile 09:30:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:30:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:30:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:30:46 INFO - Running setup.py install for mozrunner 09:30:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:30:46 INFO - Running setup.py install for mozscreenshot 09:30:46 INFO - Running setup.py install for moztest 09:30:46 INFO - Running setup.py install for mozversion 09:30:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:30:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:46 INFO - Cleaning up... 09:30:46 INFO - Return code: 0 09:30:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:30:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:46 INFO - Using env: {'DISPLAY': ':0', 09:30:46 INFO - 'HOME': '/home/cltbld', 09:30:46 INFO - 'LANG': 'en_US.UTF-8', 09:30:46 INFO - 'LANGUAGE': 'en_US:en', 09:30:46 INFO - 'LOGNAME': 'cltbld', 09:30:46 INFO - 'MAIL': '/var/mail/cltbld', 09:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:46 INFO - 'MOZ_NO_REMOTE': '1', 09:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:46 INFO - 'NO_EM_RESTART': '1', 09:30:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:46 INFO - 'PWD': '/builds/slave/test', 09:30:46 INFO - 'SHELL': '/bin/bash', 09:30:46 INFO - 'SHLVL': '1', 09:30:46 INFO - 'TERM': 'linux', 09:30:46 INFO - 'TMOUT': '86400', 09:30:46 INFO - 'USER': 'cltbld', 09:30:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:46 INFO - '_': '/tools/buildbot/bin/python'} 09:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:46 INFO - Running setup.py (path:/tmp/pip-3mVdo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:46 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)) 09:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:46 INFO - Running setup.py (path:/tmp/pip-kVsjZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:46 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)) 09:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:46 INFO - Running setup.py (path:/tmp/pip-_3mehW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:46 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)) 09:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:46 INFO - Running setup.py (path:/tmp/pip-WaMX68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:47 INFO - Running setup.py (path:/tmp/pip-UISg5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:47 INFO - Running setup.py (path:/tmp/pip-EsdulM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:47 INFO - Running setup.py (path:/tmp/pip-HHWScO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:47 INFO - Running setup.py (path:/tmp/pip-r8_DQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:47 INFO - Running setup.py (path:/tmp/pip-39_H_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:47 INFO - Running setup.py (path:/tmp/pip-tSqJo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:47 INFO - Running setup.py (path:/tmp/pip-gHHOBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:47 INFO - Running setup.py (path:/tmp/pip-1uv68r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:47 INFO - Running setup.py (path:/tmp/pip-mAgq09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:47 INFO - Running setup.py (path:/tmp/pip-ZfABON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:47 INFO - Running setup.py (path:/tmp/pip-jwi81F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:47 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)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:47 INFO - Running setup.py (path:/tmp/pip-5RCHlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:47 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)) 09:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:47 INFO - Running setup.py (path:/tmp/pip-dkWxNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:47 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)) 09:30:47 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)) 09:30:47 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)) 09:30:47 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)) 09:30:47 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)) 09:30:47 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)) 09:30:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - Downloading blessings-1.6.tar.gz 09:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:49 INFO - Installing collected packages: blessings 09:30:49 INFO - Running setup.py install for blessings 09:30:50 INFO - Successfully installed blessings 09:30:50 INFO - Cleaning up... 09:30:50 INFO - Return code: 0 09:30:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:50 INFO - Reading from file tmpfile_stdout 09:30:50 INFO - Current package versions: 09:30:50 INFO - argparse == 1.2.1 09:30:50 INFO - blessings == 1.6 09:30:50 INFO - blobuploader == 1.2.4 09:30:50 INFO - docopt == 0.6.1 09:30:50 INFO - functools32 == 3.2.3-2 09:30:50 INFO - jsonschema == 2.5.1 09:30:50 INFO - manifestparser == 1.1 09:30:50 INFO - mozInstall == 1.12 09:30:50 INFO - mozcrash == 0.17 09:30:50 INFO - mozdebug == 0.1 09:30:50 INFO - mozdevice == 0.48 09:30:50 INFO - mozfile == 1.2 09:30:50 INFO - mozhttpd == 0.7 09:30:50 INFO - mozinfo == 0.9 09:30:50 INFO - mozleak == 0.1 09:30:50 INFO - mozlog == 3.3 09:30:50 INFO - moznetwork == 0.27 09:30:50 INFO - mozprocess == 0.23 09:30:50 INFO - mozprofile == 0.28 09:30:50 INFO - mozrunner == 6.12 09:30:50 INFO - mozscreenshot == 0.1 09:30:50 INFO - mozsystemmonitor == 0.3 09:30:50 INFO - moztest == 0.7 09:30:50 INFO - mozversion == 1.4 09:30:50 INFO - psutil == 3.1.1 09:30:50 INFO - requests == 1.2.3 09:30:50 INFO - wsgiref == 0.1.2 09:30:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:50 INFO - Using env: {'DISPLAY': ':0', 09:30:50 INFO - 'HOME': '/home/cltbld', 09:30:50 INFO - 'LANG': 'en_US.UTF-8', 09:30:50 INFO - 'LANGUAGE': 'en_US:en', 09:30:50 INFO - 'LOGNAME': 'cltbld', 09:30:50 INFO - 'MAIL': '/var/mail/cltbld', 09:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:50 INFO - 'MOZ_NO_REMOTE': '1', 09:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:50 INFO - 'NO_EM_RESTART': '1', 09:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:50 INFO - 'PWD': '/builds/slave/test', 09:30:50 INFO - 'SHELL': '/bin/bash', 09:30:50 INFO - 'SHLVL': '1', 09:30:50 INFO - 'TERM': 'linux', 09:30:50 INFO - 'TMOUT': '86400', 09:30:50 INFO - 'USER': 'cltbld', 09:30:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:50 INFO - '_': '/tools/buildbot/bin/python'} 09:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:30:50 INFO - Cleaning up... 09:30:50 INFO - Return code: 0 09:30:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x250c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x273b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, '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': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', '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 09:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:30:50 INFO - Using env: {'DISPLAY': ':0', 09:30:50 INFO - 'HOME': '/home/cltbld', 09:30:50 INFO - 'LANG': 'en_US.UTF-8', 09:30:50 INFO - 'LANGUAGE': 'en_US:en', 09:30:50 INFO - 'LOGNAME': 'cltbld', 09:30:50 INFO - 'MAIL': '/var/mail/cltbld', 09:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:50 INFO - 'MOZ_NO_REMOTE': '1', 09:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:50 INFO - 'NO_EM_RESTART': '1', 09:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:50 INFO - 'PWD': '/builds/slave/test', 09:30:50 INFO - 'SHELL': '/bin/bash', 09:30:50 INFO - 'SHLVL': '1', 09:30:50 INFO - 'TERM': 'linux', 09:30:50 INFO - 'TMOUT': '86400', 09:30:50 INFO - 'USER': 'cltbld', 09:30:50 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:50 INFO - '_': '/tools/buildbot/bin/python'} 09:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:30:50 INFO - Cleaning up... 09:30:50 INFO - Return code: 0 09:30:50 INFO - Running post-action listener: _resource_record_post_action 09:30:50 INFO - Running post-action listener: _start_resource_monitoring 09:30:50 INFO - Starting resource monitoring. 09:30:50 INFO - [mozharness: 2016-09-03 16:30:50.810331Z] Finished create-virtualenv step (success) 09:30:50 INFO - [mozharness: 2016-09-03 16:30:50.810887Z] Running install step. 09:30:50 INFO - Running pre-action listener: _resource_record_pre_action 09:30:50 INFO - Running main action method: install 09:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:51 INFO - Reading from file tmpfile_stdout 09:30:51 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:51 INFO - Reading from file tmpfile_stdout 09:30:51 INFO - Output received: 09:30:51 INFO - Usage: mozinstall [options] installer 09:30:51 INFO - Options: 09:30:51 INFO - -h, --help show this help message and exit 09:30:51 INFO - -d DEST, --destination=DEST 09:30:51 INFO - Directory to install application into. [default: 09:30:51 INFO - "/builds/slave/test"] 09:30:51 INFO - --app=APP Application being installed. [default: firefox] 09:30:51 INFO - mkdir: /builds/slave/test/build/application 09:30:51 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'] 09:30:51 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 09:31:07 INFO - Reading from file tmpfile_stdout 09:31:07 INFO - Output received: 09:31:07 INFO - /builds/slave/test/build/application/firefox/firefox 09:31:07 INFO - Running post-action listener: _resource_record_post_action 09:31:07 INFO - [mozharness: 2016-09-03 16:31:07.477867Z] Finished install step (success) 09:31:07 INFO - [mozharness: 2016-09-03 16:31:07.478144Z] Running run-tests step. 09:31:07 INFO - Running pre-action listener: _resource_record_pre_action 09:31:07 INFO - Running main action method: run_tests 09:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:07 INFO - Python 2.7.3 09:31:07 INFO - Return code: 0 09:31:07 INFO - grabbing minidump binary from tooltool 09:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:07 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 0x27ca570>, '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 0x27cb470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cb900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:07 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 09:31:07 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 09:31:07 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 09:31:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:07 INFO - Return code: 0 09:31:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:31:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:31:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:07 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/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 09:31:07 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/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --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 09:31:07 INFO - Using env: {'DISPLAY': ':0', 09:31:07 INFO - 'HOME': '/home/cltbld', 09:31:07 INFO - 'LANG': 'en_US.UTF-8', 09:31:07 INFO - 'LANGUAGE': 'en_US:en', 09:31:07 INFO - 'LOGNAME': 'cltbld', 09:31:07 INFO - 'MAIL': '/var/mail/cltbld', 09:31:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:07 INFO - 'MOZ_NO_REMOTE': '1', 09:31:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:07 INFO - 'NO_EM_RESTART': '1', 09:31:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:07 INFO - 'PWD': '/builds/slave/test', 09:31:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:07 INFO - 'SHELL': '/bin/bash', 09:31:07 INFO - 'SHLVL': '1', 09:31:07 INFO - 'TERM': 'linux', 09:31:07 INFO - 'TMOUT': '86400', 09:31:07 INFO - 'USER': 'cltbld', 09:31:07 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116', 09:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:07 INFO - '_': '/tools/buildbot/bin/python'} 09:31:07 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/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--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 09:31:07 INFO - mozversion application_buildid: 20160903062049 09:31:07 INFO - mozversion application_changeset: 7f2f0754a1261a89164216070ae36e839e38d39b 09:31:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:31:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:07 INFO - mozversion application_name: Firefox 09:31:07 INFO - mozversion application_remotingname: firefox-dev 09:31:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:31:07 INFO - mozversion application_vendor: Mozilla 09:31:07 INFO - mozversion application_version: 50.0a2 09:31:07 INFO - mozversion platform_buildid: 20160903062049 09:31:07 INFO - mozversion platform_changeset: 7f2f0754a1261a89164216070ae36e839e38d39b 09:31:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:31:07 INFO - mozversion platform_version: 50.0a2 09:31:07 INFO - using testdate: 1472920267 09:31:07 INFO - actual date: 1472920267 09:31:07 INFO - starting webserver on 'localhost:34332' 09:31:07 INFO - SUITE-START | Running 2 tests 09:31:07 INFO - TEST-START | dromaeo_css 09:31:07 INFO - operating with platform_type : linux_ 09:31:07 INFO - Initialising browser for dromaeo_css test... 09:31:07 INFO - TEST-INFO | started process 25988 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbwrZpn/profile http://localhost:34332/getInfo.html) 09:31:12 INFO - PROCESS | 25988 | __metrics Screen width/height:1600/1200 09:31:12 INFO - PROCESS | 25988 | colorDepth:24 09:31:12 INFO - PROCESS | 25988 | Browser inner width/height: 1024/697 09:31:12 INFO - PROCESS | 25988 | __metrics 09:31:12 ERROR - PROCESS | 25988 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:31:13 INFO - TEST-INFO | 25988: exit 0 09:31:13 INFO - Browser initialized. 09:31:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:13 INFO - TEST-INFO | started process 26114 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbwrZpn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:25 INFO - PROCESS | 26114 | RSS: Main: 152375296 09:31:25 INFO - PROCESS | 26114 | 09:32:15 INFO - PROCESS | 26114 | 1472920335011 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] 09:34:23 INFO - PROCESS | 26114 | Cycle 1(1): loaded http://localhost:34332/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34332/tests/dromaeo/cssquery-ext.html) 09:34:25 INFO - PROCESS | 26114 | RSS: Main: 156807168 09:34:25 INFO - PROCESS | 26114 | 09:37:27 INFO - PROCESS | 26114 | Cycle 1(1): loaded http://localhost:34332/tests/dromaeo/cssquery-ext.html (next: http://localhost:34332/tests/dromaeo/cssquery-jquery.html) 09:37:27 INFO - PROCESS | 26114 | RSS: Main: 153239552 09:37:27 INFO - PROCESS | 26114 | 09:40:29 INFO - PROCESS | 26114 | Cycle 1(1): loaded http://localhost:34332/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34332/tests/dromaeo/cssquery-mootools.html) 09:40:30 INFO - PROCESS | 26114 | RSS: Main: 154095616 09:40:30 INFO - PROCESS | 26114 | 09:43:31 INFO - PROCESS | 26114 | Cycle 1(1): loaded http://localhost:34332/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34332/tests/dromaeo/cssquery-prototype.html) 09:43:32 INFO - PROCESS | 26114 | RSS: Main: 152629248 09:43:32 INFO - PROCESS | 26114 | 09:46:35 INFO - PROCESS | 26114 | Cycle 1(1): loaded http://localhost:34332/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34332/tests/dromaeo/cssquery-yui.html) 09:46:35 INFO - PROCESS | 26114 | RSS: Main: 154775552 09:46:35 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | Cycle 1(1): loaded http://localhost:34332/tests/dromaeo/cssquery-yui.html (next: http://localhost:34332/tests/dromaeo/cssquery-dojo.html) 09:49:38 INFO - PROCESS | 26114 | RSS: Main: 155070464 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | __start_tp_report 09:49:38 INFO - PROCESS | 26114 | _x_x_mozilla_page_load 09:49:38 INFO - PROCESS | 26114 | _x_x_mozilla_page_load_details 09:49:38 INFO - PROCESS | 26114 | |i|pagename|runs| 09:49:38 INFO - PROCESS | 26114 | |0;dojo.html;3301.3232287381084;3315.8342082895856;3348.5479460272873;3349.3133907548954;3373.6795004474566;5803.564732645047;5827.766889324433;5865.325487568931;5865.560082993771;5906.970465147669;5737.13942908564;5769.740096845279;5789.247397838275;5861.0915308127205;5867.559933005031;5247.344081989751;5269.394019687747;5322.840314790563;5328.786848526054;5363.54409875161;5191.117510023305;5201.427842937273;5293.9735301323435;5316.9734151329185;5392.352917649888;5309.893802123975;5317.282166907476;5321.095413779647;5330.626856120073;5344;5370.409254981949;5390.380106287788;5398.811041613544;5408.783648654049;5440.972795136038;5074.594032477393;5210.166373380241;5249.921251181236;5296.046711591912;5301.151815709506;5778;5815.9709201453725;5837.211976383176;5846.941530584682;5884.3527212007075;6179.81460556184;6199.659018753967;6232.501399887998;6236.532260080512;6250.968745156245;6012.939870601282;6083.665398403086;6135.631862088289;6312.43188113072;6316.905246421307;5811.157382179603;5817.418258174149;5818.301803783531;5842.328132264802;5850.970745146247;6322.968385158045;6354.428101470798;6358.332375100548;6359.07793369968;6360.427561519486;5907.291125064934;6020.638761674313;6025.6685882276015;6076.149339092531;6142.477889379364;6159.1069294951985;6190.257169139642;6206.286277078104;6208.068789681493;6210.378962103844;6181.876362472815;6182.907256391158;6197.4732147767045;6203.906941395882;6204.875902481925;5953.464188222994;6046.2139921809885;6077.726502307407;6110.419510146529;6177.81466555995;4707.6233901287815;4708.693934894221;4714.363560919232;4722.693024953367;4735;4192.958070419257;4202.8739137825305;4211.7262377945335;4228.534861165269;4229.661627069827;5775.104858746901;5911.083782013795;5950.464458198783;5989.760409583568;6001.909971350433;6287.465565426898;6289.402506761941;6319.494440444753;6320.62076275425;6321;6002.159697642333;6030.155778191057;6038.245219347582;6045.1234570987845;6075.936711075544;5009.77456014473;5109.642325037286;5136.768845401966;5202.973985130126;5223.869403264873;6531.738730450728;6574.046763219307;6587.077809106729;6589.80230593083;6597.934020659732;6287.465565426807;6298.464630506458;6308.27454842699;6312.558120931674;6315.96842015787;6220.284667263141;6369.108324834619;6385.233771947396;6506.967465162644;6560.606363618198;6163.599366041193;6170.074488826712;6176.938230617637;6177.6911154441195;6185.567010309415;6232.937670623236;6290.0879372491645;6291.02489114188;6292.244930608356;6295.527835412271;5905.852353691193;6010.519158467399;6058.454739073504;6058.878822423439;6154.476869466145;6127.250613123855;6132.386761323832;6136.877262454636;6153.323134455117;6153.384661533811;6057.061155520902;6062.242219722535;6083.482903953037;6119.143319935301;6132.17215675878;5878.412158784087;6087.756489740361;6106.69466526663;6117.20476338091;6137.171481849893;5953.583249172516;6061.787642471435;6072.392760723892;6089.51283897296;6097.847553811191 09:49:38 INFO - PROCESS | 26114 | |1;ext.html;573.4953241147789;615.347731404711;646.085788609118;655.8229278094915;679.9830004249897;40549;45696;45913.77043114788;45977.31034034484;46211.30683039753;46883.35908243212;47454;47468.287975680396;47596;47633.52366476334;8965.19313261808;9122.817543649124;9164.175224229817;9172.266218702522;9194.12655797698;9019.2784577234;9068.410553314046;9132.680356187535;9185.311101667368;9189.356745027852;9178.173964343208;9193.540322983858;9220.354575179741;9226.723198304027;9243.260539156885;9073.63705451781;9182.67860624875;9182.86225706615;9207.401518901317;9210.447373157633;9131.726048218497;9157;9188.540572971391;9192.908070919271;9203.171714545722;44474;44774.10451790946;44787;44855.102897941855;44995.10009799818;3919.098607320332;3951.031997160714;4022.879313620567;4056.7363121397016;4267.701260911737;35139.82430087833;35176.12059698503;35773.64226357755;35889;36553.08617284562;4612.215923293014;4657.650676199265;4671.509491503377;4687.906241875177;4694.953050469486;364338;366057;367130.9999999973;369656;371389;64928.35071649317;65117;65317;65425.67287163534;65466.67266663636;39486;40466.39300410496;68831.96752048723;68983;69373.30626693668;19421.126049327533;19500.31748888782;19510.60978780445;19539.511512212022;19634.50913727168;14930.477433289783;14975.550733478012;14988.850111498745;14994.47519336818;15123.848761512465;20957.580848382946;21003.159873604884;21003.78996210049;21124.366269011953;21204.575908481744;7416.962915185498;7643.273888980538;7735.7679269622;7750.534967901944;7786.065672119382;1021.2034613001871;1044.6291566493937;1062.6068354708807;1076.1928553584812;1103.4593049405732;34388;34484;34736.82631586826;34749;35253;1127.2391135983178;1131.8472006279046;1164.8468016663442;1182.4324324324373;1251.721866203501;17233.621310295148;17390.913045434692;17571.648567028587;17818.10909454522;17919.910400448174;571134;633982;648157;648665;955911;65690.34309656938;66461;66470.66764666242;66644.6667766658;67051;1451.7604595241667;1453.3694013834993;1457.6720237946377;1478.5490425420135;1488.6055195373235;34900;35118.47322290219;35481.82259088688;35831.10422239465;36090.45864311985;57900;58502;58577.414225857196;58628.413715864;58639.120413192984;510368;513445;516384;517344;517637;31080.844595776878;31942.201444964063;32202.677973220903;32266.83866580652;33964.49053264154;53192;53476.197857031395;53845.19232211442;54623.453765463426;55214.72392638011;42076.78961605173;46651.30023049725;47644;47950.760246198544;48420.75789621029;47002.29496557486;47209.76395118002;47640.52359476361;47715.284270735276;47976.52023479721;45944.08111837811;46083;47002.76498617622;47129;47200;40226.1954760909;40704.38943415792;40720;40812.59187408088;40923.590764091976;30208.848955755082;30778.846105769328;30943;31111.377772444877;31194.22014449657 09:49:38 INFO - PROCESS | 26114 | |2;jquery.html;414.2191968140055;415.4495293735801;424.90652056547555;437.5711802433615;441.74820352399126;19288.421347359585;19853.900730496345;20618.17527298906;20750.688739668898;20836.8958155209;19900.40298791035;20348.796512034874;20634;21204.151833926626;21318.14727410902;5950.791722289731;6142.938570614281;6181.938180618181;6185.072239164139;6191.628502289855;6086.3913608639;6092.421219984094;6097.024476083828;6150.6924653767355;6184.226971628546;5814.127880817869;5992.610480318787;5995.07076403156;5995.100734889779;6001.519878409738;5930;5978.432048955364;5978.671173085479;5988.431099045627;6004.5796794224425;5366.221897824833;5389.51494365509;5396.217548455452;5403.027455058088;5403.351597808249;14856.18290993995;14971.326290316852;23010.53978920429;23923.40191495209;24410.51178976428;2623.790096792252;2624.7637712605956;2643.2730998975244;2880.4815133276;3117.563541104247;11228.831567526493;13674.397792527367;15111.848881511263;15178.696426071418;15822;2029.4474726833985;2040.7347044884218;2042.9080691368777;2056.4502060935965;2079.5736873940955;80033.99999999942;81538;82356.17643823606;88855;92476.61285080729;11472.885271147348;11582.189246752814;11777.470013849397;11806.46870890812;11913.583024594098;16872.409465668887;16921.069341186354;16973.15134243283;16980;16985.575360615836;8590.355723320776;8705.825883482295;8793.604287807064;8874.822503549893;9207.125323094295;8369.832603347899;8429.254820732802;8493.532855692873;8691.695790647296;9139.63441462334;4812.230043193143;4941.925871111936;5026.673266237683;5121.129408000649;5149.897002059938;2035.7760646328893;2043.959120817605;2056.40892475479;2070.244360808324;2092.424583239591;961.1925982175006;976.3702411944256;1049.968500944973;1055.335138862527;1069.2034434346463;19041.523961900788;21054.472239292387;21153.89423052875;21161.78838211629;21504.56990860174;1057.8677665291839;1066.0352381095122;1097.6048622495896;1113.3431275547466;1127.301073334538;9808.65669701557;11640.476178571984;11670.766584668429;11732.530698772125;11785;487419;503352;520675;528919;535421;91615.54192229129;92946;99463.50268248613;103060;104644.47677761562;1066.7919755647574;1121.3384103379046;1124.342259778039;1135.6195674448986;1136.3920302638048;17940.91029544844;18585;18739;18772.718409224144;18991.24035038583;46565.76717116393;46820;46883;47473;48289;64338;65267.67366163139;66631;67532.66233668997;67916;9002.909970900208;9141.451512909247;9226.492542910204;9313.910334014812;9352.859707104262;9110.908890911007;11824.40877956116;12004.939975300416;14420.495282665057;14958.476453324081;11865.762684746429;12278.75442491163;12320.199187053171;12484.438200280827;12765.872361271548;11738.647840564796;12121.939390302992;12664.366781661065;12673;12877.35613219354;12160.270383777002;12238.326892021025;12267.938660306641;12283.877161228273;12578.622641320775;8858.069902660385;9148.313876459426;9220.538973051454;9294.628214871329;9346.813063738551;5641.633293835887;9173.908260917306;11634.418279086181;11975.580854670045;13130.277834719182 09:49:38 INFO - PROCESS | 26114 | |3;mootools.html;1586.2623879895848;1594.8484893935076;1767.5934535056936;1816.6275913437746;1879.699248120301;8661.61022753976;9201.447913125217;9393.812123757521;9870.654527091545;9938.155256803162;9438.622455101788;9802.068803463677;9803.117719405253;9806.460644664541;9828.312018158731;2501.099604142512;2523.8864251108653;2552.910648127316;2553.0936517536284;2560.0143944581378;2464.494778570389;2539.466711990475;2544.211294498702;2549.0823303610687;2550.1584477122597;2513.3339664988794;2518.3326418499114;2561.5517284475236;2573.2794817451145;2580.4323048929205;2432.416220107179;2434.2818868433856;2438.073532057824;2455.6193789962404;2466.1245257933356;2378.4410663494064;2380.238323736405;2391.4141035446255;2397.160993652225;2458.9754102459106;9836.852447213298;9989.200863930868;9993.600255989752;10012.89987100127;10015;5711.600187986843;5818.679972601505;5840.707964601753;5855.8536036599;5868.530517558585;3649.7810131392202;3672.944905826415;3697.7411581189326;3725.981370093132;3765.7740535567955;1176.705823544114;1216.5863606373875;1232.6733415644862;1234.8332975048343;1255.447603054653;13939.581812545639;13951.860481395157;13966.720665586732;14037.438502459889;14041.22773247471;4895.510448955147;4897.804087836446;5024.84925452237;5063;5064.392272927199;11621.76756464866;11705.122115841346;11765.058795296527;11783.587574434854;11791.29252244868;5772.740226689809;5784.305883294031;5800.738966746422;5803.245578074908;5809.331926828384;4600.976995115003;5294.841154765363;5307.442718514578;5320.52115309624;5497.972510137503;4414.205443020287;4457.910841783211;4480.820767169277;4490.956614740286;4492.842750503717;1353.4383230959238;1373.4986729843736;1392.4430227908715;1393.916365018082;1396.8812650924583;1054.1566746602687;1057.8730552333768;1061.341967979842;1066.5307264803462;1069.550788668761;6292.968535157295;6451.483881289486;6509.707063182168;6553.082568440422;6568.211814582281;499.1464595541592;500.86476651303946;503.82869824259194;510.7191044925276;511.4195388234381;4348.108637729258;4628.120657075145;4734.337192793012;4913.77887995034;4945.901081978341;12104.21322614045;12496.500139994298;12600.369981500888;12632.621021369374;12667.239965602093;12308.938455307667;12360.320182390054;12388.56640017595;12468.563600273947;12517;8997.235235005099;9023.729288121367;9156.496392698466;9160.450372977642;9163.58763855628;6567.934320656732;6767.39093481589;6816.48876334267;6822.079019332326;6918.827029324307;11219.214654974074;11319.09447244202;11467.885321146681;11490.138239631893;11540.94229528847;11413.828792567952;11511.712207194887;11595.768084638092;11679.591214307458;11750.11874109428;7927.762167134995;8452.95773521153;8629.35279854026;8631.223189912938;8807.955960220157;9473.194778443909;9527.571259293487;9542.713718588453;9585.90414095878;9587.616495340388;8251.051129119987;8345.749627511184;8357.832843343222;8415.495070295801;8503.659853605786;8273.255407013157;8317.95841020791;8323.167683231628;8337.08292087882;8371.204735550113;7726.227377262228;7912.4856884302335;7920.089189743253;7921.603919803872;7961.402894782798;5490.6431081981145;5575.553955683617;5663.093904975184;5717.857053573527;5724.599278050497;5583.69289689071;5646.85882852932;5748.051571490824;5765.682887441063;5851.765929362949 09:49:38 INFO - PROCESS | 26114 | |4;prototype.html;356.1559104921336;357.30147561517236;361.60765569357244;361.8932414937593;362.6174386022745;5770.394108618598;5841.7371218295175;5857.5941773974255;5888.440598143175;5895.941040589592;5528.585356098289;5730.825180837928;5739.196512488255;5756.194132821408;5768.4136862362875;4438.711483753554;4473.530279320673;4501.617362524173;4519.548045195471;4534.705244159136;4513.390692256553;4545.613622842063;4563.7489938053395;4572.771361431932;4581.152486789938;4534.319852022207;4542.023464955034;4552.795124219401;4557.134144512549;4566.84016059438;4310.849120280791;4475.530069342737;4476.216662084154;4480;4488.506264310923;3945.171513982067;3966.6231707987695;3968.68250539959;3970.7617542947514;3972.920541589152;5612.214289999403;5682.687452190128;5687.317521897357;5713.200151978726;5723.17014032967;4955.256711493269;5086.440491545964;5108.770105345268;5113.667611605234;5171.276021357013;6009.729562169713;6026.156338112668;6053.848653783647;6058.878822423571;6076.605020673686;4119.588041195886;4328.242557552416;4342.848000320006;4377.124575084964;4392.4289842320295;6039.78860739872;6301.1411633568205;6324.494040476751;6351.650659213741;6363;4375.256206444912;4408.977955110204;4460.531644177379;4477.1493416250805;4479.708818926824;4512.774361281923;4517.186906356887;4528.615067719281;4537;4562.67682372115;4940.086084074458;5078.162103252996;5181.119209734279;5184.326037615161;5207.21891716238;6048.304444988795;6059.273327002867;6067;6073.14975903365;6093.6039157454625;1520.8631223189966;1528.6025633335412;1540.2452798128836;1542.8842836787285;1548.6051056980398;941.4257303045136;977.9022097790164;996.9501524923725;1005.2912696548915;1008.7377281906757;5222.451642577551;5243.606729495227;5274.4198138204765;5300.1519756838725;5373.301470808848;5474.260974768435;5500.779968801203;5522.696251706195;5558.166275058693;5616.219971114325;4945.2582112683385;5243.947560524422;5271.973640131775;5280.762365693553;5290.9735451322495;2937.9265518362213;2941.602883610728;2942.382099759032;2942.749866261349;2961.600183975179;5607.719614019365;5633.22553395679;5644.604877658525;5704.971475142598;5752.395998420189;5939.732712027969;6376.649284289316;6388.169537960129;6416.518761092937;6426.907425737636;6471.932131198394;6513.348665133543;6541.901871471931;6554.574807321322;6587.670616469253;6075.2405949256345;6244.406781355764;6258.217722784652;6300.464460520907;6322.209723784527;6238.851163975905;6247.687985523091;6260.28006779208;6290.748370065146;6331.9366806331345;6321.841953951004;6419.422251997343;6535.313792051957;6550.344965503412;6559.376859198369;4480.462344518548;4515.458145022619;4520.914980404745;4526.841560545365;4535.954640453685;6147.581686363946;6228.408301211378;6306.990881458944;6402.271386725651;6465.709043092978;6110.7861224856915;6133.294671112701;6138.4525421951175;6192.628442293477;6195.659238741912;5844.707764611838;6144.050855589431;6201.782937597162;6223.315435302023;6226.595271307532;6063.393660633901;6111.541634377529;6115.969420152871;6117.480014198843;6138.048602466625;6183.134361189527;6195.96902015487;6214.6751487953725;6257.779732952213;6308.211473565804;5811.302643682785;5964.671943043174;6008.188894499362;6021.668808215415;6033.039262239878 09:49:38 INFO - PROCESS | 26114 | |5;yui.html;814.88998985137;820.3560205238884;844.9535275559846;845.7970087179079;900.828842519921;16682.332706691734;17747;17791.822081779177;17896.821031789677;17965;16011.759823602655;16191.514254572381;16440.42458513952;16455;16525;885.3448448148372;903.4172958441801;904.6924045824418;905.2034209895282;906.3293163059354;877.188600544497;880.6653471680752;882.4087861133024;888.8844450221494;888.9155530224618;939.6758118449125;942.180303136269;943.9292053096011;946.1201082992814;949.9620015199383;939.070320382823;940.6848705683605;941.9967734362916;942.3686130292721;944.8724422202984;691.381213813639;694.7498900395854;697.6965020216222;699.6047233313162;700.6671830880291;15339.386424543005;15356.846431535765;15414.614634634112;15459.613509662237;15480.22598870052;14327.140371577634;14345.354459049368;14504.34730437133;14958.62603434912;15104.093754374662;12871.806922896165;12941.547045853444;13039.543615973396;13079.673008174777;13300.3349832508;4037.7981100944835;4091.693123015756;4100.815463304159;4106.917861642751;4196.4964204295375;81519.59240203821;84445.57777211074;85536.57231713863;86179;86818.56590717069;10524.526396312185;10926.508307126198;10944.616938407276;11037.779244415227;11176.552937882556;21837;21929.671054934188;22005.559888802454;22035.449113772283;22190.334289971328;8282.461639993382;8615.741527754177;9036.0060393358;9508.144267016001;9519.52402379892;5176.430592634807;5195.220716892496;5213.7393130343335;5216.973915130476;5247.10799164143;3659.469376940329;3769.453429252798;3773.905652358713;3814.179951310426;3838.9424158637644;657.9703913323912;662.7150325360142;672.6468603982851;676.2324761395753;691.4813889582813;622.5331001748689;641.0672471553878;714.878470659989;729.6424751871651;749.4117118062295;10058.195344372432;10525.947370263253;10828.404437755842;10837.078848297982;11048.392338421463;668.391763495217;691.3604915453286;731.912170539528;739.3678404963786;745.3105877063803;5090.185570308733;5158.742062896915;5195.19474481456;5240.659357141773;5295.285136506599;111614;130567;131686.34156829154;134332;136031;93806;95657.521712391;95780;115812.42093789477;121297;86438.56780716057;88491;95915.52042239744;96851.51574242224;96862;10588.788224235319;10811.297265677706;11171.587223047014;11249.268797528135;11361.431928403712;54950.45049549454;55153;55186.448135518134;55805;56538.434615653314;77322;78831;79667.60166199131;79803;82287.17712822795;13602.659933501345;14049.719005620034;14103;14153.575392738234;14327.78508322397;16292.3483060681;16413.097279649734;16594.170291485138;16863.15684215809;16970.40603578869;16126.354945802037;16215.513534593982;16969.406070787463;17026.40407585778;17121.571960701083;15268.694626107637;15317.770233446285;15826.36694532252;15972.600684982968;16551.25519351608;15070;15787.921060394625;15861.286242119364;15940.521784346489;15967.920160399124;12465.626031219077;12488.25070495773;12923.612291631267;13422.932885335511;13499.122557033761;13162.210267383987;13202.801957970447;13235.470581176645;13381.933090334485;13532.323383830966 09:49:38 INFO - PROCESS | 26114 | __end_tp_report 09:49:38 INFO - PROCESS | 26114 | __start_cc_report 09:49:38 INFO - PROCESS | 26114 | _x_x_mozilla_cycle_collect,316 09:49:38 INFO - PROCESS | 26114 | __end_cc_report 09:49:38 INFO - PROCESS | 26114 | __startTimestamp1472921378533__endTimestamp 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | ------- Summary: start ------- 09:49:38 INFO - PROCESS | 26114 | Number of tests: 6 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | [#0] dojo.html Cycles:165 Average:5785.09 Median:6045.12 stddev:673.90 (11.1%) stddev-sans-first:647.19 09:49:38 INFO - PROCESS | 26114 | Values: 3301.3 3315.8 3348.5 3349.3 3373.7 5803.6 5827.8 5865.3 5865.6 5907.0 5737.1 5769.7 5789.2 5861.1 5867.6 5247.3 5269.4 5322.8 5328.8 5363.5 5191.1 5201.4 5294.0 5317.0 5392.4 5309.9 5317.3 5321.1 5330.6 5344.0 5370.4 5390.4 5398.8 5408.8 5441.0 5074.6 5210.2 5249.9 5296.0 5301.2 5778.0 5816.0 5837.2 5846.9 5884.4 6179.8 6199.7 6232.5 6236.5 6251.0 6012.9 6083.7 6135.6 6312.4 6316.9 5811.2 5817.4 5818.3 5842.3 5851.0 6323.0 6354.4 6358.3 6359.1 6360.4 5907.3 6020.6 6025.7 6076.1 6142.5 6159.1 6190.3 6206.3 6208.1 6210.4 6181.9 6182.9 6197.5 6203.9 6204.9 5953.5 6046.2 6077.7 6110.4 6177.8 4707.6 4708.7 4714.4 4722.7 4735.0 4193.0 4202.9 4211.7 4228.5 4229.7 5775.1 5911.1 5950.5 5989.8 6001.9 6287.5 6289.4 6319.5 6320.6 6321.0 6002.2 6030.2 6038.2 6045.1 6075.9 5009.8 5109.6 5136.8 5203.0 5223.9 6531.7 6574.0 6587.1 6589.8 6597.9 6287.5 6298.5 6308.3 6312.6 6316.0 6220.3 6369.1 6385.2 6507.0 6560.6 6163.6 6170.1 6176.9 6177.7 6185.6 6232.9 6290.1 6291.0 6292.2 6295.5 5905.9 6010.5 6058.5 6058.9 6154.5 6127.3 6132.4 6136.9 6153.3 6153.4 6057.1 6062.2 6083.5 6119.1 6132.2 5878.4 6087.8 6106.7 6117.2 6137.2 5953.6 6061.8 6072.4 6089.5 6097.8 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | [#1] ext.html Cycles:180 Average:69532.57 Median:25434.48 stddev:146052.05 (574.2%) stddev-sans-first:146369.99 09:49:38 INFO - PROCESS | 26114 | Values: 573.5 615.3 646.1 655.8 680.0 40549.0 45696.0 45913.8 45977.3 46211.3 46883.4 47454.0 47468.3 47596.0 47633.5 8965.2 9122.8 9164.2 9172.3 9194.1 9019.3 9068.4 9132.7 9185.3 9189.4 9178.2 9193.5 9220.4 9226.7 9243.3 9073.6 9182.7 9182.9 9207.4 9210.4 9131.7 9157.0 9188.5 9192.9 9203.2 44474.0 44774.1 44787.0 44855.1 44995.1 3919.1 3951.0 4022.9 4056.7 4267.7 35139.8 35176.1 35773.6 35889.0 36553.1 4612.2 4657.7 4671.5 4687.9 4695.0 364338.0 366057.0 367131.0 369656.0 371389.0 64928.4 65117.0 65317.0 65425.7 65466.7 39486.0 40466.4 68832.0 68983.0 69373.3 19421.1 19500.3 19510.6 19539.5 19634.5 14930.5 14975.6 14988.9 14994.5 15123.8 20957.6 21003.2 21003.8 21124.4 21204.6 7417.0 7643.3 7735.8 7750.5 7786.1 1021.2 1044.6 1062.6 1076.2 1103.5 34388.0 34484.0 34736.8 34749.0 35253.0 1127.2 1131.8 1164.8 1182.4 1251.7 17233.6 17390.9 17571.6 17818.1 17919.9 571134.0 633982.0 648157.0 648665.0 955911.0 65690.3 66461.0 66470.7 66644.7 67051.0 1451.8 1453.4 1457.7 1478.5 1488.6 34900.0 35118.5 35481.8 35831.1 36090.5 57900.0 58502.0 58577.4 58628.4 58639.1 510368.0 513445.0 516384.0 517344.0 517637.0 31080.8 31942.2 32202.7 32266.8 33964.5 53192.0 53476.2 53845.2 54623.5 55214.7 42076.8 46651.3 47644.0 47950.8 48420.8 47002.3 47209.8 47640.5 47715.3 47976.5 45944.1 46083.0 47002.8 47129.0 47200.0 40226.2 40704.4 40720.0 40812.6 40923.6 30208.8 30778.8 30943.0 31111.4 31194.2 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | [#2] jquery.html Cycles:180 Average:30768.77 Median:13517.15 stddev:85102.31 (629.6%) stddev-sans-first:85310.52 09:49:38 INFO - PROCESS | 26114 | Values: 414.2 415.4 424.9 437.6 441.7 19288.4 19853.9 20618.2 20750.7 20836.9 19900.4 20348.8 20634.0 21204.2 21318.1 5950.8 6142.9 6181.9 6185.1 6191.6 6086.4 6092.4 6097.0 6150.7 6184.2 5814.1 5992.6 5995.1 5995.1 6001.5 5930.0 5978.4 5978.7 5988.4 6004.6 5366.2 5389.5 5396.2 5403.0 5403.4 14856.2 14971.3 23010.5 23923.4 24410.5 2623.8 2624.8 2643.3 2880.5 3117.6 11228.8 13674.4 15111.8 15178.7 15822.0 2029.4 2040.7 2042.9 2056.5 2079.6 80034.0 81538.0 82356.2 88855.0 92476.6 11472.9 11582.2 11777.5 11806.5 11913.6 16872.4 16921.1 16973.2 16980.0 16985.6 8590.4 8705.8 8793.6 8874.8 9207.1 8369.8 8429.3 8493.5 8691.7 9139.6 4812.2 4941.9 5026.7 5121.1 5149.9 2035.8 2044.0 2056.4 2070.2 2092.4 961.2 976.4 1050.0 1055.3 1069.2 19041.5 21054.5 21153.9 21161.8 21504.6 1057.9 1066.0 1097.6 1113.3 1127.3 9808.7 11640.5 11670.8 11732.5 11785.0 487419.0 503352.0 520675.0 528919.0 535421.0 91615.5 92946.0 99463.5 103060.0 104644.5 1066.8 1121.3 1124.3 1135.6 1136.4 17940.9 18585.0 18739.0 18772.7 18991.2 46565.8 46820.0 46883.0 47473.0 48289.0 64338.0 65267.7 66631.0 67532.7 67916.0 9002.9 9141.5 9226.5 9313.9 9352.9 9110.9 11824.4 12004.9 14420.5 14958.5 11865.8 12278.8 12320.2 12484.4 12765.9 11738.6 12121.9 12664.4 12673.0 12877.4 12160.3 12238.3 12267.9 12283.9 12578.6 8858.1 9148.3 9220.5 9294.6 9346.8 5641.6 9173.9 11634.4 11975.6 13130.3 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | [#3] mootools.html Cycles:180 Average:6448.04 Median:4814.92 stddev:3769.89 (78.3%) stddev-sans-first:3762.76 09:49:38 INFO - PROCESS | 26114 | Values: 1586.3 1594.8 1767.6 1816.6 1879.7 8661.6 9201.4 9393.8 9870.7 9938.2 9438.6 9802.1 9803.1 9806.5 9828.3 2501.1 2523.9 2552.9 2553.1 2560.0 2464.5 2539.5 2544.2 2549.1 2550.2 2513.3 2518.3 2561.6 2573.3 2580.4 2432.4 2434.3 2438.1 2455.6 2466.1 2378.4 2380.2 2391.4 2397.2 2459.0 9836.9 9989.2 9993.6 10012.9 10015.0 5711.6 5818.7 5840.7 5855.9 5868.5 3649.8 3672.9 3697.7 3726.0 3765.8 1176.7 1216.6 1232.7 1234.8 1255.4 13939.6 13951.9 13966.7 14037.4 14041.2 4895.5 4897.8 5024.8 5063.0 5064.4 11621.8 11705.1 11765.1 11783.6 11791.3 5772.7 5784.3 5800.7 5803.2 5809.3 4601.0 5294.8 5307.4 5320.5 5498.0 4414.2 4457.9 4480.8 4491.0 4492.8 1353.4 1373.5 1392.4 1393.9 1396.9 1054.2 1057.9 1061.3 1066.5 1069.6 6293.0 6451.5 6509.7 6553.1 6568.2 499.1 500.9 503.8 510.7 511.4 4348.1 4628.1 4734.3 4913.8 4945.9 12104.2 12496.5 12600.4 12632.6 12667.2 12308.9 12360.3 12388.6 12468.6 12517.0 8997.2 9023.7 9156.5 9160.5 9163.6 6567.9 6767.4 6816.5 6822.1 6918.8 11219.2 11319.1 11467.9 11490.1 11540.9 11413.8 11511.7 11595.8 11679.6 11750.1 7927.8 8453.0 8629.4 8631.2 8808.0 9473.2 9527.6 9542.7 9585.9 9587.6 8251.1 8345.7 8357.8 8415.5 8503.7 8273.3 8318.0 8323.2 8337.1 8371.2 7726.2 7912.5 7920.1 7921.6 7961.4 5490.6 5575.6 5663.1 5717.9 5724.6 5583.7 5646.9 5748.1 5765.7 5851.8 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | [#4] prototype.html Cycles:180 Average:5052.79 Median:5638.92 stddev:1504.77 (26.7%) stddev-sans-first:1467.12 09:49:38 INFO - PROCESS | 26114 | Values: 356.2 357.3 361.6 361.9 362.6 5770.4 5841.7 5857.6 5888.4 5895.9 5528.6 5730.8 5739.2 5756.2 5768.4 4438.7 4473.5 4501.6 4519.5 4534.7 4513.4 4545.6 4563.7 4572.8 4581.2 4534.3 4542.0 4552.8 4557.1 4566.8 4310.8 4475.5 4476.2 4480.0 4488.5 3945.2 3966.6 3968.7 3970.8 3972.9 5612.2 5682.7 5687.3 5713.2 5723.2 4955.3 5086.4 5108.8 5113.7 5171.3 6009.7 6026.2 6053.8 6058.9 6076.6 4119.6 4328.2 4342.8 4377.1 4392.4 6039.8 6301.1 6324.5 6351.7 6363.0 4375.3 4409.0 4460.5 4477.1 4479.7 4512.8 4517.2 4528.6 4537.0 4562.7 4940.1 5078.2 5181.1 5184.3 5207.2 6048.3 6059.3 6067.0 6073.1 6093.6 1520.9 1528.6 1540.2 1542.9 1548.6 941.4 977.9 997.0 1005.3 1008.7 5222.5 5243.6 5274.4 5300.2 5373.3 5474.3 5500.8 5522.7 5558.2 5616.2 4945.3 5243.9 5272.0 5280.8 5291.0 2937.9 2941.6 2942.4 2942.7 2961.6 5607.7 5633.2 5644.6 5705.0 5752.4 5939.7 6376.6 6388.2 6416.5 6426.9 6471.9 6513.3 6541.9 6554.6 6587.7 6075.2 6244.4 6258.2 6300.5 6322.2 6238.9 6247.7 6260.3 6290.7 6331.9 6321.8 6419.4 6535.3 6550.3 6559.4 4480.5 4515.5 4520.9 4526.8 4536.0 6147.6 6228.4 6307.0 6402.3 6465.7 6110.8 6133.3 6138.5 6192.6 6195.7 5844.7 6144.1 6201.8 6223.3 6226.6 6063.4 6111.5 6116.0 6117.5 6138.0 6183.1 6196.0 6214.7 6257.8 6308.2 5811.3 5964.7 6008.2 6021.7 6033.0 09:49:38 INFO - PROCESS | 26114 | 09:49:38 INFO - PROCESS | 26114 | [#5] yui.html Cycles:180 Average:22711.40 Median:3826.56 stddev:32686.66 (854.2%) stddev-sans-first:32737.01 09:49:38 INFO - PROCESS | 26114 | Values: 814.9 820.4 845.0 845.8 900.8 16682.3 17747.0 17791.8 17896.8 17965.0 16011.8 16191.5 16440.4 16455.0 16525.0 885.3 903.4 904.7 905.2 906.3 877.2 880.7 882.4 888.9 888.9 939.7 942.2 943.9 946.1 950.0 939.1 940.7 942.0 942.4 944.9 691.4 694.7 697.7 699.6 700.7 15339.4 15356.8 15414.6 15459.6 15480.2 14327.1 14345.4 14504.3 14958.6 15104.1 12871.8 12941.5 13039.5 13079.7 13300.3 4037.8 4091.7 4100.8 4106.9 4196.5 81519.6 84445.6 85536.6 86179.0 86818.6 10524.5 10926.5 10944.6 11037.8 11176.6 21837.0 21929.7 22005.6 22035.4 22190.3 8282.5 8615.7 9036.0 9508.1 9519.5 5176.4 5195.2 5213.7 5217.0 5247.1 3659.5 3769.5 3773.9 3814.2 3838.9 658.0 662.7 672.6 676.2 691.5 622.5 641.1 714.9 729.6 749.4 10058.2 10525.9 10828.4 10837.1 11048.4 668.4 691.4 731.9 739.4 745.3 5090.2 5158.7 5195.2 5240.7 5295.3 111614.0 130567.0 131686.3 134332.0 136031.0 93806.0 95657.5 95780.0 115812.4 121297.0 86438.6 88491.0 95915.5 96851.5 96862.0 10588.8 10811.3 11171.6 11249.3 11361.4 54950.5 55153.0 55186.4 55805.0 56538.4 77322.0 78831.0 79667.6 79803.0 82287.2 13602.7 14049.7 14103.0 14153.6 14327.8 16292.3 16413.1 16594.2 16863.2 16970.4 16126.4 16215.5 16969.4 17026.4 17121.6 15268.7 15317.8 15826.4 15972.6 16551.3 15070.0 15787.9 15861.3 15940.5 15967.9 12465.6 12488.3 12923.6 13422.9 13499.1 13162.2 13202.8 13235.5 13381.9 13532.3 09:49:38 INFO - PROCESS | 26114 | -------- Summary: end -------- 09:49:38 INFO - PROCESS | 26114 | 09:49:39 INFO - TEST-INFO | 26114: exit 0 09:49:39 INFO - TEST-OK | dromaeo_css | took 1111432ms 09:49:39 INFO - TEST-START | kraken 09:49:39 INFO - operating with platform_type : linux_ 09:49:39 INFO - Initialising browser for kraken test... 09:49:39 INFO - TEST-INFO | started process 26382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsICuo7/profile http://localhost:34332/getInfo.html) 09:49:43 INFO - PROCESS | 26382 | __metrics Screen width/height:1600/1200 09:49:43 INFO - PROCESS | 26382 | colorDepth:24 09:49:43 INFO - PROCESS | 26382 | Browser inner width/height: 1024/697 09:49:43 INFO - PROCESS | 26382 | __metrics 09:49:44 ERROR - PROCESS | 26382 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:49:45 INFO - TEST-INFO | 26382: exit 0 09:49:45 INFO - Browser initialized. 09:49:45 INFO - Running cycle 1/1 for kraken test... 09:49:45 INFO - TEST-INFO | started process 26509 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsICuo7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:56 INFO - PROCESS | 26509 | RSS: Main: 152969216 09:49:56 INFO - PROCESS | 26509 | 09:49:59 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34332/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:00 INFO - PROCESS | 26509 | RSS: Main: 158416896 09:50:00 INFO - PROCESS | 26509 | 09:50:02 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34332/tests/kraken/driver.html?testName=audio-dft) 09:50:03 INFO - PROCESS | 26509 | RSS: Main: 156540928 09:50:03 INFO - PROCESS | 26509 | 09:50:06 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34332/tests/kraken/driver.html?testName=audio-fft) 09:50:06 INFO - PROCESS | 26509 | RSS: Main: 157696000 09:50:06 INFO - PROCESS | 26509 | 09:50:09 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34332/tests/kraken/driver.html?testName=audio-oscillator) 09:50:09 INFO - PROCESS | 26509 | RSS: Main: 157011968 09:50:09 INFO - PROCESS | 26509 | 09:50:11 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34332/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:12 INFO - PROCESS | 26509 | RSS: Main: 157335552 09:50:12 INFO - PROCESS | 26509 | 09:50:15 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34332/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:16 INFO - PROCESS | 26509 | RSS: Main: 156647424 09:50:16 INFO - PROCESS | 26509 | 09:50:19 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34332/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:20 INFO - PROCESS | 26509 | RSS: Main: 156839936 09:50:20 INFO - PROCESS | 26509 | 09:50:23 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34332/tests/kraken/driver.html?testName=json-parse-financial) 09:50:23 INFO - PROCESS | 26509 | RSS: Main: 158097408 09:50:23 INFO - PROCESS | 26509 | 09:50:25 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34332/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:25 INFO - PROCESS | 26509 | RSS: Main: 157425664 09:50:25 INFO - PROCESS | 26509 | 09:50:27 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:28 INFO - PROCESS | 26509 | RSS: Main: 157560832 09:50:28 INFO - PROCESS | 26509 | 09:50:30 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:30 INFO - PROCESS | 26509 | RSS: Main: 157700096 09:50:30 INFO - PROCESS | 26509 | 09:50:33 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:33 INFO - PROCESS | 26509 | RSS: Main: 158404608 09:50:33 INFO - PROCESS | 26509 | 09:50:36 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:37 INFO - PROCESS | 26509 | RSS: Main: 158081024 09:50:37 INFO - PROCESS | 26509 | 09:50:38 INFO - PROCESS | 26509 | Cycle 1(1): loaded http://localhost:34332/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34332/tests/kraken/driver.html?testName=ai-astar) 09:50:39 INFO - PROCESS | 26509 | RSS: Main: 158867456 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | __start_tp_report 09:50:39 INFO - PROCESS | 26509 | _x_x_mozilla_page_load 09:50:39 INFO - PROCESS | 26509 | _x_x_mozilla_page_load_details 09:50:39 INFO - PROCESS | 26509 | |i|pagename|runs| 09:50:39 INFO - PROCESS | 26509 | |0;ai-astar;102;112;106;111;110;111;111;113;110;110 09:50:39 INFO - PROCESS | 26509 | |1;audio-beat-detection;143;118;151;173;123;147;142;117;170;117 09:50:39 INFO - PROCESS | 26509 | |2;audio-dft;163;163;164;160;161;164;165;161;163;163 09:50:39 INFO - PROCESS | 26509 | |3;audio-fft;76;77;75;177;76;75;76;77;163;75 09:50:39 INFO - PROCESS | 26509 | |4;audio-oscillator;83;84;148;83;84;84;128;84;86;134 09:50:39 INFO - PROCESS | 26509 | |5;imaging-gaussian-blur;117;117;118;117;117;118;120;117;117;117 09:50:39 INFO - PROCESS | 26509 | |6;imaging-darkroom;147;147;149;147;147;148;148;147;147;146 09:50:39 INFO - PROCESS | 26509 | |7;imaging-desaturate;96;95;97;95;95;95;95;97;95;95 09:50:39 INFO - PROCESS | 26509 | |8;json-parse-financial;52;53;52;51;51;53;53;52;51;52 09:50:39 INFO - PROCESS | 26509 | |9;json-stringify-tinderbox;62;59;59;59;59;59;60;60;59;59 09:50:39 INFO - PROCESS | 26509 | |10;stanford-crypto-aes;73;73;74;73;72;73;72;73;78;72 09:50:39 INFO - PROCESS | 26509 | |11;stanford-crypto-ccm;124;118;119;125;125;119;121;120;161;126 09:50:39 INFO - PROCESS | 26509 | |12;stanford-crypto-pbkdf2;159;158;154;152;155;155;157;153;155;157 09:50:39 INFO - PROCESS | 26509 | |13;stanford-crypto-sha256-iterative;67;69;63;64;63;62;62;61;62;63 09:50:39 INFO - PROCESS | 26509 | __end_tp_report 09:50:39 INFO - PROCESS | 26509 | __start_cc_report 09:50:39 INFO - PROCESS | 26509 | _x_x_mozilla_cycle_collect,823 09:50:39 INFO - PROCESS | 26509 | __end_cc_report 09:50:39 INFO - PROCESS | 26509 | __startTimestamp1472921439159__endTimestamp 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | ------- Summary: start ------- 09:50:39 INFO - PROCESS | 26509 | Number of tests: 14 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.24 (2.9%) stddev-sans-first:1.94 09:50:39 INFO - PROCESS | 26509 | Values: 102.0 112.0 106.0 111.0 110.0 111.0 111.0 113.0 110.0 110.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:145.00 stddev:21.09 (14.5%) stddev-sans-first:22.34 09:50:39 INFO - PROCESS | 26509 | Values: 143.0 118.0 151.0 173.0 123.0 147.0 142.0 117.0 170.0 117.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.57 (1.0%) stddev-sans-first:1.66 09:50:39 INFO - PROCESS | 26509 | Values: 163.0 163.0 164.0 160.0 161.0 164.0 165.0 161.0 163.0 163.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#3] audio-fft Cycles:10 Average:94.70 Median:76.00 stddev:39.83 (52.4%) stddev-sans-first:41.67 09:50:39 INFO - PROCESS | 26509 | Values: 76.0 77.0 75.0 177.0 76.0 75.0 76.0 77.0 163.0 75.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:84.00 stddev:25.91 (30.8%) stddev-sans-first:26.76 09:50:39 INFO - PROCESS | 26509 | Values: 83.0 84.0 148.0 83.0 84.0 84.0 128.0 84.0 86.0 134.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.00 stddev:0.97 (0.8%) stddev-sans-first:1.01 09:50:39 INFO - PROCESS | 26509 | Values: 117.0 117.0 118.0 117.0 117.0 118.0 120.0 117.0 117.0 117.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 09:50:39 INFO - PROCESS | 26509 | Values: 147.0 147.0 149.0 147.0 147.0 148.0 148.0 147.0 147.0 146.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 09:50:39 INFO - PROCESS | 26509 | Values: 96.0 95.0 97.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.00 stddev:0.82 (1.6%) stddev-sans-first:0.87 09:50:39 INFO - PROCESS | 26509 | Values: 52.0 53.0 52.0 51.0 51.0 53.0 53.0 52.0 51.0 52.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.00 stddev:0.97 (1.6%) stddev-sans-first:0.44 09:50:39 INFO - PROCESS | 26509 | Values: 62.0 59.0 59.0 59.0 59.0 59.0 60.0 60.0 59.0 59.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:1.77 (2.4%) stddev-sans-first:1.87 09:50:39 INFO - PROCESS | 26509 | Values: 73.0 73.0 74.0 73.0 72.0 73.0 72.0 73.0 78.0 72.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#11] stanford-crypto-ccm Cycles:10 Average:125.80 Median:124.50 stddev:12.71 (10.2%) stddev-sans-first:13.46 09:50:39 INFO - PROCESS | 26509 | Values: 124.0 118.0 119.0 125.0 125.0 119.0 121.0 120.0 161.0 126.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.50 Median:156.00 stddev:2.22 (1.4%) stddev-sans-first:1.96 09:50:39 INFO - PROCESS | 26509 | Values: 159.0 158.0 154.0 152.0 155.0 155.0 157.0 153.0 155.0 157.0 09:50:39 INFO - PROCESS | 26509 | 09:50:39 INFO - PROCESS | 26509 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:63.00 stddev:2.50 (4.0%) stddev-sans-first:2.33 09:50:39 INFO - PROCESS | 26509 | Values: 67.0 69.0 63.0 64.0 63.0 62.0 62.0 61.0 62.0 63.0 09:50:39 INFO - PROCESS | 26509 | -------- Summary: end -------- 09:50:39 INFO - PROCESS | 26509 | 09:50:40 INFO - TEST-INFO | 26509: exit 0 09:50:40 INFO - TEST-OK | kraken | took 60920ms 09:50:40 INFO - SUITE-END | took 1172s 09:50:40 INFO - Completed test suite (00:19:32) 09:50:40 INFO - javascript benchmark 09:50:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7855.537953370063, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.3232287381084, 3315.8342082895856, 3348.5479460272873, 3349.3133907548954, 3373.6795004474566, 5803.564732645047, 5827.766889324433, 5865.325487568931, 5865.560082993771, 5906.970465147669, 5737.13942908564, 5769.740096845279, 5789.247397838275, 5861.0915308127205, 5867.559933005031, 5247.344081989751, 5269.394019687747, 5322.840314790563, 5328.786848526054, 5363.54409875161, 5191.117510023305, 5201.427842937273, 5293.9735301323435, 5316.9734151329185, 5392.352917649888, 5309.893802123975, 5317.282166907476, 5321.095413779647, 5330.626856120073, 5344.0, 5370.409254981949, 5390.380106287788, 5398.811041613544, 5408.783648654049, 5440.972795136038, 5074.594032477393, 5210.166373380241, 5249.921251181236, 5296.046711591912, 5301.151815709506, 5778.0, 5815.9709201453725, 5837.211976383176, 5846.941530584682, 5884.3527212007075, 6179.81460556184, 6199.659018753967, 6232.501399887998, 6236.532260080512, 6250.968745156245, 6012.939870601282, 6083.665398403086, 6135.631862088289, 6312.43188113072, 6316.905246421307, 5811.157382179603, 5817.418258174149, 5818.301803783531, 5842.328132264802, 5850.970745146247, 6322.968385158045, 6354.428101470798, 6358.332375100548, 6359.07793369968, 6360.427561519486, 5907.291125064934, 6020.638761674313, 6025.6685882276015, 6076.149339092531, 6142.477889379364, 6159.1069294951985, 6190.257169139642, 6206.286277078104, 6208.068789681493, 6210.378962103844, 6181.876362472815, 6182.907256391158, 6197.4732147767045, 6203.906941395882, 6204.875902481925, 5953.464188222994, 6046.2139921809885, 6077.726502307407, 6110.419510146529, 6177.81466555995, 4707.6233901287815, 4708.693934894221, 4714.363560919232, 4722.693024953367, 4735.0, 4192.958070419257, 4202.8739137825305, 4211.7262377945335, 4228.534861165269, 4229.661627069827, 5775.104858746901, 5911.083782013795, 5950.464458198783, 5989.760409583568, 6001.909971350433, 6287.465565426898, 6289.402506761941, 6319.494440444753, 6320.62076275425, 6321.0, 6002.159697642333, 6030.155778191057, 6038.245219347582, 6045.1234570987845, 6075.936711075544, 5009.77456014473, 5109.642325037286, 5136.768845401966, 5202.973985130126, 5223.869403264873, 6531.738730450728, 6574.046763219307, 6587.077809106729, 6589.80230593083, 6597.934020659732, 6287.465565426807, 6298.464630506458, 6308.27454842699, 6312.558120931674, 6315.96842015787, 6220.284667263141, 6369.108324834619, 6385.233771947396, 6506.967465162644, 6560.606363618198, 6163.599366041193, 6170.074488826712, 6176.938230617637, 6177.6911154441195, 6185.567010309415, 6232.937670623236, 6290.0879372491645, 6291.02489114188, 6292.244930608356, 6295.527835412271, 5905.852353691193, 6010.519158467399, 6058.454739073504, 6058.878822423439, 6154.476869466145, 6127.250613123855, 6132.386761323832, 6136.877262454636, 6153.323134455117, 6153.384661533811, 6057.061155520902, 6062.242219722535, 6083.482903953037, 6119.143319935301, 6132.17215675878, 5878.412158784087, 6087.756489740361, 6106.69466526663, 6117.20476338091, 6137.171481849893, 5953.583249172516, 6061.787642471435, 6072.392760723892, 6089.51283897296, 6097.847553811191], "value": 5738.311016664865, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.4953241147789, 615.347731404711, 646.085788609118, 655.8229278094915, 679.9830004249897, 40549.0, 45696.0, 45913.77043114788, 45977.31034034484, 46211.30683039753, 46883.35908243212, 47454.0, 47468.287975680396, 47596.0, 47633.52366476334, 8965.19313261808, 9122.817543649124, 9164.175224229817, 9172.266218702522, 9194.12655797698, 9019.2784577234, 9068.410553314046, 9132.680356187535, 9185.311101667368, 9189.356745027852, 9178.173964343208, 9193.540322983858, 9220.354575179741, 9226.723198304027, 9243.260539156885, 9073.63705451781, 9182.67860624875, 9182.86225706615, 9207.401518901317, 9210.447373157633, 9131.726048218497, 9157.0, 9188.540572971391, 9192.908070919271, 9203.171714545722, 44474.0, 44774.10451790946, 44787.0, 44855.102897941855, 44995.10009799818, 3919.098607320332, 3951.031997160714, 4022.879313620567, 4056.7363121397016, 4267.701260911737, 35139.82430087833, 35176.12059698503, 35773.64226357755, 35889.0, 36553.08617284562, 4612.215923293014, 4657.650676199265, 4671.509491503377, 4687.906241875177, 4694.953050469486, 364338.0, 366057.0, 367130.9999999973, 369656.0, 371389.0, 64928.35071649317, 65117.0, 65317.0, 65425.67287163534, 65466.67266663636, 39486.0, 40466.39300410496, 68831.96752048723, 68983.0, 69373.30626693668, 19421.126049327533, 19500.31748888782, 19510.60978780445, 19539.511512212022, 19634.50913727168, 14930.477433289783, 14975.550733478012, 14988.850111498745, 14994.47519336818, 15123.848761512465, 20957.580848382946, 21003.159873604884, 21003.78996210049, 21124.366269011953, 21204.575908481744, 7416.962915185498, 7643.273888980538, 7735.7679269622, 7750.534967901944, 7786.065672119382, 1021.2034613001871, 1044.6291566493937, 1062.6068354708807, 1076.1928553584812, 1103.4593049405732, 34388.0, 34484.0, 34736.82631586826, 34749.0, 35253.0, 1127.2391135983178, 1131.8472006279046, 1164.8468016663442, 1182.4324324324373, 1251.721866203501, 17233.621310295148, 17390.913045434692, 17571.648567028587, 17818.10909454522, 17919.910400448174, 571134.0, 633982.0, 648157.0, 648665.0, 955911.0, 65690.34309656938, 66461.0, 66470.66764666242, 66644.6667766658, 67051.0, 1451.7604595241667, 1453.3694013834993, 1457.6720237946377, 1478.5490425420135, 1488.6055195373235, 34900.0, 35118.47322290219, 35481.82259088688, 35831.10422239465, 36090.45864311985, 57900.0, 58502.0, 58577.414225857196, 58628.413715864, 58639.120413192984, 510368.0, 513445.0, 516384.0, 517344.0, 517637.0, 31080.844595776878, 31942.201444964063, 32202.677973220903, 32266.83866580652, 33964.49053264154, 53192.0, 53476.197857031395, 53845.19232211442, 54623.453765463426, 55214.72392638011, 42076.78961605173, 46651.30023049725, 47644.0, 47950.760246198544, 48420.75789621029, 47002.29496557486, 47209.76395118002, 47640.52359476361, 47715.284270735276, 47976.52023479721, 45944.08111837811, 46083.0, 47002.76498617622, 47129.0, 47200.0, 40226.1954760909, 40704.38943415792, 40720.0, 40812.59187408088, 40923.590764091976, 30208.848955755082, 30778.846105769328, 30943.0, 31111.377772444877, 31194.22014449657], "value": 21999.32894352748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.2191968140055, 415.4495293735801, 424.90652056547555, 437.5711802433615, 441.74820352399126, 19288.421347359585, 19853.900730496345, 20618.17527298906, 20750.688739668898, 20836.8958155209, 19900.40298791035, 20348.796512034874, 20634.0, 21204.151833926626, 21318.14727410902, 5950.791722289731, 6142.938570614281, 6181.938180618181, 6185.072239164139, 6191.628502289855, 6086.3913608639, 6092.421219984094, 6097.024476083828, 6150.6924653767355, 6184.226971628546, 5814.127880817869, 5992.610480318787, 5995.07076403156, 5995.100734889779, 6001.519878409738, 5930.0, 5978.432048955364, 5978.671173085479, 5988.431099045627, 6004.5796794224425, 5366.221897824833, 5389.51494365509, 5396.217548455452, 5403.027455058088, 5403.351597808249, 14856.18290993995, 14971.326290316852, 23010.53978920429, 23923.40191495209, 24410.51178976428, 2623.790096792252, 2624.7637712605956, 2643.2730998975244, 2880.4815133276, 3117.563541104247, 11228.831567526493, 13674.397792527367, 15111.848881511263, 15178.696426071418, 15822.0, 2029.4474726833985, 2040.7347044884218, 2042.9080691368777, 2056.4502060935965, 2079.5736873940955, 80033.99999999942, 81538.0, 82356.17643823606, 88855.0, 92476.61285080729, 11472.885271147348, 11582.189246752814, 11777.470013849397, 11806.46870890812, 11913.583024594098, 16872.409465668887, 16921.069341186354, 16973.15134243283, 16980.0, 16985.575360615836, 8590.355723320776, 8705.825883482295, 8793.604287807064, 8874.822503549893, 9207.125323094295, 8369.832603347899, 8429.254820732802, 8493.532855692873, 8691.695790647296, 9139.63441462334, 4812.230043193143, 4941.925871111936, 5026.673266237683, 5121.129408000649, 5149.897002059938, 2035.7760646328893, 2043.959120817605, 2056.40892475479, 2070.244360808324, 2092.424583239591, 961.1925982175006, 976.3702411944256, 1049.968500944973, 1055.335138862527, 1069.2034434346463, 19041.523961900788, 21054.472239292387, 21153.89423052875, 21161.78838211629, 21504.56990860174, 1057.8677665291839, 1066.0352381095122, 1097.6048622495896, 1113.3431275547466, 1127.301073334538, 9808.65669701557, 11640.476178571984, 11670.766584668429, 11732.530698772125, 11785.0, 487419.0, 503352.0, 520675.0, 528919.0, 535421.0, 91615.54192229129, 92946.0, 99463.50268248613, 103060.0, 104644.47677761562, 1066.7919755647574, 1121.3384103379046, 1124.342259778039, 1135.6195674448986, 1136.3920302638048, 17940.91029544844, 18585.0, 18739.0, 18772.718409224144, 18991.24035038583, 46565.76717116393, 46820.0, 46883.0, 47473.0, 48289.0, 64338.0, 65267.67366163139, 66631.0, 67532.66233668997, 67916.0, 9002.909970900208, 9141.451512909247, 9226.492542910204, 9313.910334014812, 9352.859707104262, 9110.908890911007, 11824.40877956116, 12004.939975300416, 14420.495282665057, 14958.476453324081, 11865.762684746429, 12278.75442491163, 12320.199187053171, 12484.438200280827, 12765.872361271548, 11738.647840564796, 12121.939390302992, 12664.366781661065, 12673.0, 12877.35613219354, 12160.270383777002, 12238.326892021025, 12267.938660306641, 12283.877161228273, 12578.622641320775, 8858.069902660385, 9148.313876459426, 9220.538973051454, 9294.628214871329, 9346.813063738551, 5641.633293835887, 9173.908260917306, 11634.418279086181, 11975.580854670045, 13130.277834719182], "value": 9809.107318572052, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.2623879895848, 1594.8484893935076, 1767.5934535056936, 1816.6275913437746, 1879.699248120301, 8661.61022753976, 9201.447913125217, 9393.812123757521, 9870.654527091545, 9938.155256803162, 9438.622455101788, 9802.068803463677, 9803.117719405253, 9806.460644664541, 9828.312018158731, 2501.099604142512, 2523.8864251108653, 2552.910648127316, 2553.0936517536284, 2560.0143944581378, 2464.494778570389, 2539.466711990475, 2544.211294498702, 2549.0823303610687, 2550.1584477122597, 2513.3339664988794, 2518.3326418499114, 2561.5517284475236, 2573.2794817451145, 2580.4323048929205, 2432.416220107179, 2434.2818868433856, 2438.073532057824, 2455.6193789962404, 2466.1245257933356, 2378.4410663494064, 2380.238323736405, 2391.4141035446255, 2397.160993652225, 2458.9754102459106, 9836.852447213298, 9989.200863930868, 9993.600255989752, 10012.89987100127, 10015.0, 5711.600187986843, 5818.679972601505, 5840.707964601753, 5855.8536036599, 5868.530517558585, 3649.7810131392202, 3672.944905826415, 3697.7411581189326, 3725.981370093132, 3765.7740535567955, 1176.705823544114, 1216.5863606373875, 1232.6733415644862, 1234.8332975048343, 1255.447603054653, 13939.581812545639, 13951.860481395157, 13966.720665586732, 14037.438502459889, 14041.22773247471, 4895.510448955147, 4897.804087836446, 5024.84925452237, 5063.0, 5064.392272927199, 11621.76756464866, 11705.122115841346, 11765.058795296527, 11783.587574434854, 11791.29252244868, 5772.740226689809, 5784.305883294031, 5800.738966746422, 5803.245578074908, 5809.331926828384, 4600.976995115003, 5294.841154765363, 5307.442718514578, 5320.52115309624, 5497.972510137503, 4414.205443020287, 4457.910841783211, 4480.820767169277, 4490.956614740286, 4492.842750503717, 1353.4383230959238, 1373.4986729843736, 1392.4430227908715, 1393.916365018082, 1396.8812650924583, 1054.1566746602687, 1057.8730552333768, 1061.341967979842, 1066.5307264803462, 1069.550788668761, 6292.968535157295, 6451.483881289486, 6509.707063182168, 6553.082568440422, 6568.211814582281, 499.1464595541592, 500.86476651303946, 503.82869824259194, 510.7191044925276, 511.4195388234381, 4348.108637729258, 4628.120657075145, 4734.337192793012, 4913.77887995034, 4945.901081978341, 12104.21322614045, 12496.500139994298, 12600.369981500888, 12632.621021369374, 12667.239965602093, 12308.938455307667, 12360.320182390054, 12388.56640017595, 12468.563600273947, 12517.0, 8997.235235005099, 9023.729288121367, 9156.496392698466, 9160.450372977642, 9163.58763855628, 6567.934320656732, 6767.39093481589, 6816.48876334267, 6822.079019332326, 6918.827029324307, 11219.214654974074, 11319.09447244202, 11467.885321146681, 11490.138239631893, 11540.94229528847, 11413.828792567952, 11511.712207194887, 11595.768084638092, 11679.591214307458, 11750.11874109428, 7927.762167134995, 8452.95773521153, 8629.35279854026, 8631.223189912938, 8807.955960220157, 9473.194778443909, 9527.571259293487, 9542.713718588453, 9585.90414095878, 9587.616495340388, 8251.051129119987, 8345.749627511184, 8357.832843343222, 8415.495070295801, 8503.659853605786, 8273.255407013157, 8317.95841020791, 8323.167683231628, 8337.08292087882, 8371.204735550113, 7726.227377262228, 7912.4856884302335, 7920.089189743253, 7921.603919803872, 7961.402894782798, 5490.6431081981145, 5575.553955683617, 5663.093904975184, 5717.857053573527, 5724.599278050497, 5583.69289689071, 5646.85882852932, 5748.051571490824, 5765.682887441063, 5851.765929362949], "value": 5031.208456397238, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.1559104921336, 357.30147561517236, 361.60765569357244, 361.8932414937593, 362.6174386022745, 5770.394108618598, 5841.7371218295175, 5857.5941773974255, 5888.440598143175, 5895.941040589592, 5528.585356098289, 5730.825180837928, 5739.196512488255, 5756.194132821408, 5768.4136862362875, 4438.711483753554, 4473.530279320673, 4501.617362524173, 4519.548045195471, 4534.705244159136, 4513.390692256553, 4545.613622842063, 4563.7489938053395, 4572.771361431932, 4581.152486789938, 4534.319852022207, 4542.023464955034, 4552.795124219401, 4557.134144512549, 4566.84016059438, 4310.849120280791, 4475.530069342737, 4476.216662084154, 4480.0, 4488.506264310923, 3945.171513982067, 3966.6231707987695, 3968.68250539959, 3970.7617542947514, 3972.920541589152, 5612.214289999403, 5682.687452190128, 5687.317521897357, 5713.200151978726, 5723.17014032967, 4955.256711493269, 5086.440491545964, 5108.770105345268, 5113.667611605234, 5171.276021357013, 6009.729562169713, 6026.156338112668, 6053.848653783647, 6058.878822423571, 6076.605020673686, 4119.588041195886, 4328.242557552416, 4342.848000320006, 4377.124575084964, 4392.4289842320295, 6039.78860739872, 6301.1411633568205, 6324.494040476751, 6351.650659213741, 6363.0, 4375.256206444912, 4408.977955110204, 4460.531644177379, 4477.1493416250805, 4479.708818926824, 4512.774361281923, 4517.186906356887, 4528.615067719281, 4537.0, 4562.67682372115, 4940.086084074458, 5078.162103252996, 5181.119209734279, 5184.326037615161, 5207.21891716238, 6048.304444988795, 6059.273327002867, 6067.0, 6073.14975903365, 6093.6039157454625, 1520.8631223189966, 1528.6025633335412, 1540.2452798128836, 1542.8842836787285, 1548.6051056980398, 941.4257303045136, 977.9022097790164, 996.9501524923725, 1005.2912696548915, 1008.7377281906757, 5222.451642577551, 5243.606729495227, 5274.4198138204765, 5300.1519756838725, 5373.301470808848, 5474.260974768435, 5500.779968801203, 5522.696251706195, 5558.166275058693, 5616.219971114325, 4945.2582112683385, 5243.947560524422, 5271.973640131775, 5280.762365693553, 5290.9735451322495, 2937.9265518362213, 2941.602883610728, 2942.382099759032, 2942.749866261349, 2961.600183975179, 5607.719614019365, 5633.22553395679, 5644.604877658525, 5704.971475142598, 5752.395998420189, 5939.732712027969, 6376.649284289316, 6388.169537960129, 6416.518761092937, 6426.907425737636, 6471.932131198394, 6513.348665133543, 6541.901871471931, 6554.574807321322, 6587.670616469253, 6075.2405949256345, 6244.406781355764, 6258.217722784652, 6300.464460520907, 6322.209723784527, 6238.851163975905, 6247.687985523091, 6260.28006779208, 6290.748370065146, 6331.9366806331345, 6321.841953951004, 6419.422251997343, 6535.313792051957, 6550.344965503412, 6559.376859198369, 4480.462344518548, 4515.458145022619, 4520.914980404745, 4526.841560545365, 4535.954640453685, 6147.581686363946, 6228.408301211378, 6306.990881458944, 6402.271386725651, 6465.709043092978, 6110.7861224856915, 6133.294671112701, 6138.4525421951175, 6192.628442293477, 6195.659238741912, 5844.707764611838, 6144.050855589431, 6201.782937597162, 6223.315435302023, 6226.595271307532, 6063.393660633901, 6111.541634377529, 6115.969420152871, 6117.480014198843, 6138.048602466625, 6183.134361189527, 6195.96902015487, 6214.6751487953725, 6257.779732952213, 6308.211473565804, 5811.302643682785, 5964.671943043174, 6008.188894499362, 6021.668808215415, 6033.039262239878], "value": 4570.7232138687, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.88998985137, 820.3560205238884, 844.9535275559846, 845.7970087179079, 900.828842519921, 16682.332706691734, 17747.0, 17791.822081779177, 17896.821031789677, 17965.0, 16011.759823602655, 16191.514254572381, 16440.42458513952, 16455.0, 16525.0, 885.3448448148372, 903.4172958441801, 904.6924045824418, 905.2034209895282, 906.3293163059354, 877.188600544497, 880.6653471680752, 882.4087861133024, 888.8844450221494, 888.9155530224618, 939.6758118449125, 942.180303136269, 943.9292053096011, 946.1201082992814, 949.9620015199383, 939.070320382823, 940.6848705683605, 941.9967734362916, 942.3686130292721, 944.8724422202984, 691.381213813639, 694.7498900395854, 697.6965020216222, 699.6047233313162, 700.6671830880291, 15339.386424543005, 15356.846431535765, 15414.614634634112, 15459.613509662237, 15480.22598870052, 14327.140371577634, 14345.354459049368, 14504.34730437133, 14958.62603434912, 15104.093754374662, 12871.806922896165, 12941.547045853444, 13039.543615973396, 13079.673008174777, 13300.3349832508, 4037.7981100944835, 4091.693123015756, 4100.815463304159, 4106.917861642751, 4196.4964204295375, 81519.59240203821, 84445.57777211074, 85536.57231713863, 86179.0, 86818.56590717069, 10524.526396312185, 10926.508307126198, 10944.616938407276, 11037.779244415227, 11176.552937882556, 21837.0, 21929.671054934188, 22005.559888802454, 22035.449113772283, 22190.334289971328, 8282.461639993382, 8615.741527754177, 9036.0060393358, 9508.144267016001, 9519.52402379892, 5176.430592634807, 5195.220716892496, 5213.7393130343335, 5216.973915130476, 5247.10799164143, 3659.469376940329, 3769.453429252798, 3773.905652358713, 3814.179951310426, 3838.9424158637644, 657.9703913323912, 662.7150325360142, 672.6468603982851, 676.2324761395753, 691.4813889582813, 622.5331001748689, 641.0672471553878, 714.878470659989, 729.6424751871651, 749.4117118062295, 10058.195344372432, 10525.947370263253, 10828.404437755842, 10837.078848297982, 11048.392338421463, 668.391763495217, 691.3604915453286, 731.912170539528, 739.3678404963786, 745.3105877063803, 5090.185570308733, 5158.742062896915, 5195.19474481456, 5240.659357141773, 5295.285136506599, 111614.0, 130567.0, 131686.34156829154, 134332.0, 136031.0, 93806.0, 95657.521712391, 95780.0, 115812.42093789477, 121297.0, 86438.56780716057, 88491.0, 95915.52042239744, 96851.51574242224, 96862.0, 10588.788224235319, 10811.297265677706, 11171.587223047014, 11249.268797528135, 11361.431928403712, 54950.45049549454, 55153.0, 55186.448135518134, 55805.0, 56538.434615653314, 77322.0, 78831.0, 79667.60166199131, 79803.0, 82287.17712822795, 13602.659933501345, 14049.719005620034, 14103.0, 14153.575392738234, 14327.78508322397, 16292.3483060681, 16413.097279649734, 16594.170291485138, 16863.15684215809, 16970.40603578869, 16126.354945802037, 16215.513534593982, 16969.406070787463, 17026.40407585778, 17121.571960701083, 15268.694626107637, 15317.770233446285, 15826.36694532252, 15972.600684982968, 16551.25519351608, 15070.0, 15787.921060394625, 15861.286242119364, 15940.521784346489, 15967.920160399124, 12465.626031219077, 12488.25070495773, 12923.612291631267, 13422.932885335511, 13499.122557033761, 13162.210267383987, 13202.801957970447, 13235.470581176645, 13381.933090334485, 13532.323383830966], "value": 8251.509481990599, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1496.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 106.0, 111.0, 110.0, 111.0, 111.0, 113.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 118.0, 151.0, 173.0, 123.0, 147.0, 142.0, 117.0, 170.0, 117.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 160.0, 161.0, 164.0, 165.0, 161.0, 163.0, 163.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 177.0, 76.0, 75.0, 76.0, 77.0, 163.0, 75.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 148.0, 83.0, 84.0, 84.0, 128.0, 84.0, 86.0, 134.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 118.0, 120.0, 117.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 149.0, 147.0, 147.0, 148.0, 148.0, 147.0, 147.0, 146.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 51.0, 51.0, 53.0, 53.0, 52.0, 51.0, 52.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 59.0, 59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 73.0, 72.0, 73.0, 72.0, 73.0, 78.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 118.0, 119.0, 125.0, 125.0, 119.0, 121.0, 120.0, 161.0, 126.0], "value": 125.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 154.0, 152.0, 155.0, 155.0, 157.0, 153.0, 155.0, 157.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 63.0, 64.0, 63.0, 62.0, 62.0, 61.0, 62.0, 63.0], "value": 63.6, "unit": "score"}]}]} 09:50:40 INFO - Return code: 0 09:50:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:50:40 ERROR - # TBPL SUCCESS # 09:50:40 INFO - Running post-action listener: _resource_record_post_action 09:50:40 INFO - [mozharness: 2016-09-03 16:50:40.464099Z] Finished run-tests step (success) 09:50:40 INFO - Running post-run listener: _resource_record_post_run 09:50:40 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328220672; Read time: 12; Write time: 263752 09:50:40 INFO - TinderboxPrint: CPU usage
13.3% 09:50:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:50:40 INFO - TinderboxPrint: I/O write bytes / time
328,220,672 / 263,752 09:50:40 INFO - TinderboxPrint: CPU idle
8,227.3 (86.7%) 09:50:40 INFO - TinderboxPrint: CPU user
1,201.2 (12.7%) 09:50:40 INFO - TinderboxPrint: Swap in / out
0 / 0 09:50:40 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78819328; Read time: 0; Write time: 94504 09:50:40 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 248782848; Read time: 0; Write time: 169228 09:50:41 INFO - Running post-run listener: _upload_blobber_files 09:50:41 INFO - Blob upload gear active. 09:50:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:41 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 09:50:41 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'] 09:50:41 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 09:50:41 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:44 INFO - (blobuploader) - INFO - Done attempting. 09:50:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:50:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:50:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:45 INFO - (blobuploader) - INFO - Done attempting. 09:50:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:50:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:50:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:46 INFO - (blobuploader) - INFO - Done attempting. 09:50:46 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:46 INFO - Return code: 0 09:50:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c048a2c51fa8aa62b5f4742b64069954eebb14414379f5c98f65a2a9b2af6e464592117859553a1bbff12a62971e9ff3373d59238ee60f7b2cb47287a812e7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36a8d6cec328c9190106d6fbb90a5f1421042f98db8697c29c877b8e97bb3d1cb049b5e1334b7a9865fa192777685b3ef0f8bcdab4ef565a3affa36b5f229dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbfa5dfcf0c998c422909379505c79561294e224953e8809e9e9573fd4a22375c624f4ffa58036612e5d4fa53675e0bd91d4e67f11e17e9d7fd2e15d0aacc01f"} 09:50:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:46 INFO - Contents: 09:50:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c048a2c51fa8aa62b5f4742b64069954eebb14414379f5c98f65a2a9b2af6e464592117859553a1bbff12a62971e9ff3373d59238ee60f7b2cb47287a812e7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36a8d6cec328c9190106d6fbb90a5f1421042f98db8697c29c877b8e97bb3d1cb049b5e1334b7a9865fa192777685b3ef0f8bcdab4ef565a3affa36b5f229dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbfa5dfcf0c998c422909379505c79561294e224953e8809e9e9573fd4a22375c624f4ffa58036612e5d4fa53675e0bd91d4e67f11e17e9d7fd2e15d0aacc01f"} 09:50:46 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:46 INFO - Copying logs to upload dir... 09:50:46 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.426510 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-03 09:50:45.248272) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 09:50:45.251512) ========= 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=0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116 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/c048a2c51fa8aa62b5f4742b64069954eebb14414379f5c98f65a2a9b2af6e464592117859553a1bbff12a62971e9ff3373d59238ee60f7b2cb47287a812e7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36a8d6cec328c9190106d6fbb90a5f1421042f98db8697c29c877b8e97bb3d1cb049b5e1334b7a9865fa192777685b3ef0f8bcdab4ef565a3affa36b5f229dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbfa5dfcf0c998c422909379505c79561294e224953e8809e9e9573fd4a22375c624f4ffa58036612e5d4fa53675e0bd91d4e67f11e17e9d7fd2e15d0aacc01f"} build_url:https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011260 build_url: 'https://queue.taskcluster.net/v1/task/EoupJmcmRFOGlrWrA-Ozcw/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/c048a2c51fa8aa62b5f4742b64069954eebb14414379f5c98f65a2a9b2af6e464592117859553a1bbff12a62971e9ff3373d59238ee60f7b2cb47287a812e7a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/36a8d6cec328c9190106d6fbb90a5f1421042f98db8697c29c877b8e97bb3d1cb049b5e1334b7a9865fa192777685b3ef0f8bcdab4ef565a3affa36b5f229dc7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbfa5dfcf0c998c422909379505c79561294e224953e8809e9e9573fd4a22375c624f4ffa58036612e5d4fa53675e0bd91d4e67f11e17e9d7fd2e15d0aacc01f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 09:50:45.276253) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:50:45.276584) ========= 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=0e2a256762fed810e2f0f810000001b3-1472918469.597758-2062185116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004386 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 09:50:45.362075) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 09:50:45.362389) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 09:50:45.362676) ========= ========= Total master_lag: 0.18 =========