builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-029 starttime: 1473762909.29 results: success (0) buildid: 20160913004005 builduid: cb2bff480acd4cf5834ac85ac015eb5b revision: 696a981b6d534a7015d6f2ba3d2379af620e9b26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.290278) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.290785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.291105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.306139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.306454) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-13 03:35:09-- 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% 48.7M=0s 2016-09-13 03:35:10 (48.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112076 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.434524) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.435196) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023437 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.488132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:09.488511) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 696a981b6d534a7015d6f2ba3d2379af620e9b26 --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 696a981b6d534a7015d6f2ba3d2379af620e9b26 --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=9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-13 03:35:10,194 truncating revision to first 12 chars 2016-09-13 03:35:10,194 Setting DEBUG logging. 2016-09-13 03:35:10,194 attempt 1/10 2016-09-13 03:35:10,194 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/696a981b6d53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-13 03:35:10,559 unpacking tar archive at: mozilla-aurora-696a981b6d53/testing/mozharness/ program finished with exit code 0 elapsedTime=0.694500 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:10.197384) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:10.197804) ========= script_repo_revision: 696a981b6d534a7015d6f2ba3d2379af620e9b26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:10.198251) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:10.198574) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 03:35:10.207360) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 29 mins, 8 secs) (at 2016-09-13 03:35:10.207677) ========= /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=9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:35:10 INFO - MultiFileLogger online at 20160913 03:35:10 in /builds/slave/test 03:35:10 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 03:35:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:35:10 INFO - {'append_to_log': False, 03:35:10 INFO - 'base_work_dir': '/builds/slave/test', 03:35:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:35:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:35:10 INFO - 'branch': 'Mozilla-Aurora', 03:35:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:35:10 INFO - 'config_files': ('talos/linux_config.py',), 03:35:10 INFO - 'default_actions': ('clobber', 03:35:10 INFO - 'read-buildbot-config', 03:35:10 INFO - 'download-and-extract', 03:35:10 INFO - 'populate-webroot', 03:35:10 INFO - 'create-virtualenv', 03:35:10 INFO - 'install', 03:35:10 INFO - 'run-tests'), 03:35:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:35:10 INFO - 'download_minidump_stackwalk': True, 03:35:10 INFO - 'download_symbols': 'ondemand', 03:35:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:35:10 INFO - 'tooltool.py': '/tools/tooltool.py', 03:35:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:35:10 INFO - '/tools/misc-python/virtualenv.py')}, 03:35:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:35:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:35:10 INFO - 'installer_path': 'installer.exe', 03:35:10 INFO - 'log_level': 'info', 03:35:10 INFO - 'log_name': 'talos', 03:35:10 INFO - 'log_to_console': True, 03:35:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:35:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:35:10 INFO - 'opt_config_files': (), 03:35:10 INFO - 'pip_index': False, 03:35:10 INFO - 'sps_profile': False, 03:35:10 INFO - 'sps_profile_interval': 0, 03:35:10 INFO - 'suite': 'dromaeojs-e10s', 03:35:10 INFO - 'system_bits': '32', 03:35:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:35:10 INFO - 'title': 'talos-linux64-ix-029', 03:35:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:35:10 INFO - 'use_talos_json': True, 03:35:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:35:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:35:10 INFO - 'work_dir': 'build'} 03:35:10 INFO - [mozharness: 2016-09-13 10:35:10.998190Z] Running clobber step. 03:35:10 INFO - Running pre-action listener: _resource_record_pre_action 03:35:10 INFO - Running main action method: clobber 03:35:10 INFO - rmtree: /builds/slave/test/build 03:35:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:35:11 INFO - Running post-action listener: _resource_record_post_action 03:35:11 INFO - [mozharness: 2016-09-13 10:35:11.460205Z] Finished clobber step (success) 03:35:11 INFO - [mozharness: 2016-09-13 10:35:11.460308Z] Running read-buildbot-config step. 03:35:11 INFO - Running pre-action listener: _resource_record_pre_action 03:35:11 INFO - Running main action method: read_buildbot_config 03:35:11 INFO - Using buildbot properties: 03:35:11 INFO - { 03:35:11 INFO - "project": "", 03:35:11 INFO - "product": "firefox", 03:35:11 INFO - "script_repo_revision": "production", 03:35:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 03:35:11 INFO - "repository": "", 03:35:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 03:35:11 INFO - "buildid": "20160913004005", 03:35:11 INFO - "stage_platform": "linux64-pgo", 03:35:11 INFO - "basedir": "/builds/slave/test", 03:35:11 INFO - "nightly_build": "True", 03:35:11 INFO - "buildnumber": 548, 03:35:11 INFO - "slavename": "talos-linux64-ix-029", 03:35:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:35:11 INFO - "platform": "ubuntu64_hw", 03:35:11 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:35:11 INFO - "branch": "mozilla-aurora", 03:35:11 INFO - "repo_path": "releases/mozilla-aurora", 03:35:11 INFO - "revision": "696a981b6d534a7015d6f2ba3d2379af620e9b26", 03:35:11 INFO - "pgo_build": "True", 03:35:11 INFO - "builduid": "cb2bff480acd4cf5834ac85ac015eb5b", 03:35:11 INFO - "slavebuilddir": "test" 03:35:11 INFO - } 03:35:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:35:11 INFO - Running post-action listener: _resource_record_post_action 03:35:11 INFO - [mozharness: 2016-09-13 10:35:11.462820Z] Finished read-buildbot-config step (success) 03:35:11 INFO - [mozharness: 2016-09-13 10:35:11.462908Z] Running download-and-extract step. 03:35:11 INFO - Running pre-action listener: _resource_record_pre_action 03:35:11 INFO - Running main action method: download_and_extract 03:35:11 INFO - mkdir: /builds/slave/test/build/tests 03:35:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:35:11 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:35:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:35:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:35:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:11 INFO - retry: Failed, sleeping 30 seconds before retrying 03:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:35:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:35:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:35:41 INFO - retry: Failed, sleeping 60 seconds before retrying 03:36:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:36:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:36:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:36:41 INFO - Caught exception: HTTP Error 404: Not Found 03:36:41 INFO - Caught exception: HTTP Error 404: Not Found 03:36:41 INFO - Caught exception: HTTP Error 404: Not Found 03:36:41 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:41 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:36:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:36:42 INFO - Downloaded 1361 bytes. 03:36:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:36:42 INFO - Using the following test package requirements: 03:36:42 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:36:42 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:36:42 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:36:42 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'jsshell-linux-x86_64.zip'], 03:36:42 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:36:42 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:36:42 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:36:42 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:36:42 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:36:42 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:36:42 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:36:42 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:36:42 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:36:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:36:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:36:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:36:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:36:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:36:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:36:42 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:37:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:37:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:12 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:38:12 INFO - Caught exception: HTTP Error 404: Not Found 03:38:12 INFO - Caught exception: HTTP Error 404: Not Found 03:38:12 INFO - Caught exception: HTTP Error 404: Not Found 03:38:12 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:12 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:13 INFO - Downloaded 21770035 bytes. 03:38:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:38:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:38:15 INFO - Return code: 0 03:38:15 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:38:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:15 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:38:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:38:15 INFO - retry: Failed, sleeping 30 seconds before retrying 03:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:38:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:38:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:38:45 INFO - retry: Failed, sleeping 60 seconds before retrying 03:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:39:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:39:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:39:45 INFO - Caught exception: HTTP Error 404: Not Found 03:39:45 INFO - Caught exception: HTTP Error 404: Not Found 03:39:45 INFO - Caught exception: HTTP Error 404: Not Found 03:39:45 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:45 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:39:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:39:45 INFO - Downloaded 21770035 bytes. 03:39:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:39:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:39:47 INFO - Return code: 0 03:39:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:47 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:39:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:39:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:39:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:39:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:39:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:39:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:39:47 INFO - retry: Failed, sleeping 30 seconds before retrying 03:40:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:40:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:40:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:40:17 INFO - retry: Failed, sleeping 60 seconds before retrying 03:41:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:41:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:41:17 INFO - Caught exception: HTTP Error 404: Not Found 03:41:17 INFO - Caught exception: HTTP Error 404: Not Found 03:41:17 INFO - Caught exception: HTTP Error 404: Not Found 03:41:17 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:41:17 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:18 INFO - Downloaded 14413740 bytes. 03:41:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:41:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:41:19 INFO - Return code: 0 03:41:19 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')]} 03:41:19 INFO - https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:41:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:41:19 INFO - retry: Failed, sleeping 30 seconds before retrying 03:41:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:41:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:41:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:41:49 INFO - retry: Failed, sleeping 60 seconds before retrying 03:42:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:42:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:42:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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! 03:42:49 INFO - Caught exception: HTTP Error 404: Not Found 03:42:49 INFO - Caught exception: HTTP Error 404: Not Found 03:42:49 INFO - Caught exception: HTTP Error 404: Not Found 03:42:49 INFO - trying https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:49 INFO - Downloading https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:42:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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 03:42:50 INFO - Downloaded 63870898 bytes. 03:42:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:50 INFO - mkdir: /builds/slave/test/properties 03:42:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:42:50 INFO - Writing to file /builds/slave/test/properties/build_url 03:42:50 INFO - Contents: 03:42:50 INFO - build_url:https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:42:51 INFO - Running post-action listener: _resource_record_post_action 03:42:51 INFO - Running post-action listener: set_extra_try_arguments 03:42:51 INFO - [mozharness: 2016-09-13 10:42:51.272777Z] Finished download-and-extract step (success) 03:42:51 INFO - [mozharness: 2016-09-13 10:42:51.273008Z] Running populate-webroot step. 03:42:51 INFO - Running pre-action listener: _resource_record_pre_action 03:42:51 INFO - Running main action method: populate_webroot 03:42:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:42:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:42:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:42:51 INFO - '--tppagecycles', 03:42:51 INFO - '7'], 03:42:51 INFO - 'tests': ['tsvgm']}}, 03:42:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:42:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:42:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:42:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:42:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:42:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:42:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['damp', 'tps']}, 03:42:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'tests': ['damp', 'tps']}, 03:42:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['dromaeo_dom']}, 03:42:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:42:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['basic_compositor_video']}, 03:42:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:42:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['a11yr', 03:42:51 INFO - 'ts_paint', 03:42:51 INFO - 'tpaint', 03:42:51 INFO - 'sessionrestore', 03:42:51 INFO - 'sessionrestore_no_auto_restore', 03:42:51 INFO - 'tabpaint']}, 03:42:51 INFO - 'other-e10s': {'tests': ['a11yr', 03:42:51 INFO - 'ts_paint', 03:42:51 INFO - 'tpaint', 03:42:51 INFO - 'sessionrestore', 03:42:51 INFO - 'sessionrestore_no_auto_restore', 03:42:51 INFO - 'tabpaint']}, 03:42:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:42:51 INFO - 'ts_paint', 03:42:51 INFO - 'tpaint', 03:42:51 INFO - 'sessionrestore', 03:42:51 INFO - 'sessionrestore_no_auto_restore', 03:42:51 INFO - 'tabpaint']}, 03:42:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:42:51 INFO - 'ts_paint', 03:42:51 INFO - 'tpaint', 03:42:51 INFO - 'sessionrestore', 03:42:51 INFO - 'sessionrestore_no_auto_restore', 03:42:51 INFO - 'tabpaint']}, 03:42:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['a11yr', 03:42:51 INFO - 'ts_paint', 03:42:51 INFO - 'tpaint', 03:42:51 INFO - 'sessionrestore', 03:42:51 INFO - 'sessionrestore_no_auto_restore', 03:42:51 INFO - 'tabpaint']}, 03:42:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['a11yr', 03:42:51 INFO - 'ts_paint', 03:42:51 INFO - 'tpaint', 03:42:51 INFO - 'sessionrestore', 03:42:51 INFO - 'sessionrestore_no_auto_restore', 03:42:51 INFO - 'tabpaint']}, 03:42:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['tsvgx', 03:42:51 INFO - 'tsvgr_opacity', 03:42:51 INFO - 'tart', 03:42:51 INFO - 'tscrollx', 03:42:51 INFO - 'cart']}, 03:42:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:42:51 INFO - 'tsvgr_opacity', 03:42:51 INFO - 'tart', 03:42:51 INFO - 'tscrollx', 03:42:51 INFO - 'cart']}, 03:42:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'talos_options': ['--disable-e10s'], 03:42:51 INFO - 'tests': ['tp5o']}, 03:42:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'tests': ['tp5o']}, 03:42:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'talos_options': ['--disable-e10s', 03:42:51 INFO - '--xperf_path', 03:42:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:42:51 INFO - 'tests': ['tp5n']}, 03:42:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:42:51 INFO - 'talos_options': ['--xperf_path', 03:42:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:42:51 INFO - 'tests': ['tp5n']}}, 03:42:51 INFO - 'talos.zip': {'path': '', 03:42:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:42:51 INFO - Running post-action listener: _resource_record_post_action 03:42:51 INFO - [mozharness: 2016-09-13 10:42:51.291001Z] Finished populate-webroot step (success) 03:42:51 INFO - [mozharness: 2016-09-13 10:42:51.291090Z] Running create-virtualenv step. 03:42:51 INFO - Running pre-action listener: _resource_record_pre_action 03:42:51 INFO - Running main action method: create_virtualenv 03:42:51 INFO - Creating virtualenv /builds/slave/test/build/venv 03:42:51 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 03:42:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:42:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:42:51 INFO - Using real prefix '/usr' 03:42:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:42:51 INFO - Installing distribute.............................................................................................................................................................................................done. 03:42:53 INFO - Installing pip.................done. 03:42:53 INFO - Return code: 0 03:42:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:42: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')]} 03:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42: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')]} 03:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:42:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:42:53 INFO - Using env: {'DISPLAY': ':0', 03:42:53 INFO - 'HOME': '/home/cltbld', 03:42:53 INFO - 'LANG': 'en_US.UTF-8', 03:42:53 INFO - 'LANGUAGE': 'en_US:en', 03:42:53 INFO - 'LOGNAME': 'cltbld', 03:42:53 INFO - 'MAIL': '/var/mail/cltbld', 03:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:53 INFO - 'MOZ_NO_REMOTE': '1', 03:42:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:53 INFO - 'NO_EM_RESTART': '1', 03:42:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:53 INFO - 'PWD': '/builds/slave/test', 03:42:53 INFO - 'SHELL': '/bin/bash', 03:42:53 INFO - 'SHLVL': '1', 03:42:53 INFO - 'TERM': 'linux', 03:42:53 INFO - 'TMOUT': '86400', 03:42:53 INFO - 'USER': 'cltbld', 03:42:53 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:53 INFO - '_': '/tools/buildbot/bin/python'} 03:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:54 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 03:42:54 INFO - Cleaning up... 03:42:54 INFO - Return code: 0 03:42:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:42:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:42:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:42:54 INFO - Using env: {'DISPLAY': ':0', 03:42:54 INFO - 'HOME': '/home/cltbld', 03:42:54 INFO - 'LANG': 'en_US.UTF-8', 03:42:54 INFO - 'LANGUAGE': 'en_US:en', 03:42:54 INFO - 'LOGNAME': 'cltbld', 03:42:54 INFO - 'MAIL': '/var/mail/cltbld', 03:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:54 INFO - 'MOZ_NO_REMOTE': '1', 03:42:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:54 INFO - 'NO_EM_RESTART': '1', 03:42:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:54 INFO - 'PWD': '/builds/slave/test', 03:42:54 INFO - 'SHELL': '/bin/bash', 03:42:54 INFO - 'SHLVL': '1', 03:42:54 INFO - 'TERM': 'linux', 03:42:54 INFO - 'TMOUT': '86400', 03:42:54 INFO - 'USER': 'cltbld', 03:42:54 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:54 INFO - '_': '/tools/buildbot/bin/python'} 03:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:54 INFO - Downloading/unpacking psutil>=3.1.1 03:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:42:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:42:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:42:56 INFO - Installing collected packages: psutil 03:42:56 INFO - Running setup.py install for psutil 03:42:56 INFO - building 'psutil._psutil_linux' extension 03:42:56 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 03:42:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:42:56 INFO - building 'psutil._psutil_posix' extension 03:42:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:42:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:42:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:42:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:42:56 INFO - Successfully installed psutil 03:42:56 INFO - Cleaning up... 03:42:57 INFO - Return code: 0 03:42:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:42:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:42:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:42:57 INFO - Using env: {'DISPLAY': ':0', 03:42:57 INFO - 'HOME': '/home/cltbld', 03:42:57 INFO - 'LANG': 'en_US.UTF-8', 03:42:57 INFO - 'LANGUAGE': 'en_US:en', 03:42:57 INFO - 'LOGNAME': 'cltbld', 03:42:57 INFO - 'MAIL': '/var/mail/cltbld', 03:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:57 INFO - 'MOZ_NO_REMOTE': '1', 03:42:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:57 INFO - 'NO_EM_RESTART': '1', 03:42:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:57 INFO - 'PWD': '/builds/slave/test', 03:42:57 INFO - 'SHELL': '/bin/bash', 03:42:57 INFO - 'SHLVL': '1', 03:42:57 INFO - 'TERM': 'linux', 03:42:57 INFO - 'TMOUT': '86400', 03:42:57 INFO - 'USER': 'cltbld', 03:42:57 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:57 INFO - '_': '/tools/buildbot/bin/python'} 03:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:42:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:42:59 INFO - Installing collected packages: mozsystemmonitor 03:42:59 INFO - Running setup.py install for mozsystemmonitor 03:42:59 INFO - Successfully installed mozsystemmonitor 03:42:59 INFO - Cleaning up... 03:42:59 INFO - Return code: 0 03:42:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:42:59 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')]} 03:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:59 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')]} 03:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:42:59 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:42:59 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 03:42:59 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 03:42:59 INFO - Using env: {'DISPLAY': ':0', 03:42:59 INFO - 'HOME': '/home/cltbld', 03:42:59 INFO - 'LANG': 'en_US.UTF-8', 03:42:59 INFO - 'LANGUAGE': 'en_US:en', 03:42:59 INFO - 'LOGNAME': 'cltbld', 03:42:59 INFO - 'MAIL': '/var/mail/cltbld', 03:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:59 INFO - 'MOZ_NO_REMOTE': '1', 03:42:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:59 INFO - 'NO_EM_RESTART': '1', 03:42:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:59 INFO - 'PWD': '/builds/slave/test', 03:42:59 INFO - 'SHELL': '/bin/bash', 03:42:59 INFO - 'SHLVL': '1', 03:42:59 INFO - 'TERM': 'linux', 03:42:59 INFO - 'TMOUT': '86400', 03:42:59 INFO - 'USER': 'cltbld', 03:42:59 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:59 INFO - '_': '/tools/buildbot/bin/python'} 03:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:42:59 INFO - Downloading/unpacking jsonschema==2.5.1 03:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:42:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:42:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:43:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:43:02 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 03:43:02 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 03:43:02 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 03:43:02 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 03:43:02 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 03:43:02 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 03:43:02 INFO - Downloading functools32-3.2.3-2.tar.gz 03:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:43:02 INFO - warning: no files found matching '*.txt' 03:43:02 INFO - no previously-included directories found matching 'build' 03:43:02 INFO - no previously-included directories found matching 'dist' 03:43:02 INFO - no previously-included directories found matching '.git*' 03:43:02 INFO - Installing collected packages: jsonschema, functools32 03:43:02 INFO - Running setup.py install for functools32 03:43:02 INFO - warning: no files found matching '*.txt' 03:43:02 INFO - no previously-included directories found matching 'build' 03:43:02 INFO - no previously-included directories found matching 'dist' 03:43:02 INFO - no previously-included directories found matching '.git*' 03:43:02 INFO - Successfully installed jsonschema functools32 03:43:02 INFO - Cleaning up... 03:43:02 INFO - Return code: 0 03:43:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:43:02 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')]} 03:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:02 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')]} 03:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:02 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:43:02 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 03:43:02 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 03:43:02 INFO - Using env: {'DISPLAY': ':0', 03:43:02 INFO - 'HOME': '/home/cltbld', 03:43:02 INFO - 'LANG': 'en_US.UTF-8', 03:43:02 INFO - 'LANGUAGE': 'en_US:en', 03:43:02 INFO - 'LOGNAME': 'cltbld', 03:43:02 INFO - 'MAIL': '/var/mail/cltbld', 03:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:02 INFO - 'MOZ_NO_REMOTE': '1', 03:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:02 INFO - 'NO_EM_RESTART': '1', 03:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:02 INFO - 'PWD': '/builds/slave/test', 03:43:02 INFO - 'SHELL': '/bin/bash', 03:43:02 INFO - 'SHLVL': '1', 03:43:02 INFO - 'TERM': 'linux', 03:43:02 INFO - 'TMOUT': '86400', 03:43:02 INFO - 'USER': 'cltbld', 03:43:02 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:02 INFO - '_': '/tools/buildbot/bin/python'} 03:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:43:03 INFO - Cleaning up... 03:43:03 INFO - Return code: 0 03:43:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:43:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:03 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:43:03 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 03:43:03 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 03:43:03 INFO - Using env: {'DISPLAY': ':0', 03:43:03 INFO - 'HOME': '/home/cltbld', 03:43:03 INFO - 'LANG': 'en_US.UTF-8', 03:43:03 INFO - 'LANGUAGE': 'en_US:en', 03:43:03 INFO - 'LOGNAME': 'cltbld', 03:43:03 INFO - 'MAIL': '/var/mail/cltbld', 03:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:03 INFO - 'MOZ_NO_REMOTE': '1', 03:43:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:03 INFO - 'NO_EM_RESTART': '1', 03:43:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:03 INFO - 'PWD': '/builds/slave/test', 03:43:03 INFO - 'SHELL': '/bin/bash', 03:43:03 INFO - 'SHLVL': '1', 03:43:03 INFO - 'TERM': 'linux', 03:43:03 INFO - 'TMOUT': '86400', 03:43:03 INFO - 'USER': 'cltbld', 03:43:03 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:03 INFO - '_': '/tools/buildbot/bin/python'} 03:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:05 INFO - Downloading blobuploader-1.2.4.tar.gz 03:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:43:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:43:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:05 INFO - Downloading docopt-0.6.1.tar.gz 03:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:43:05 INFO - Installing collected packages: blobuploader, requests, docopt 03:43:05 INFO - Running setup.py install for blobuploader 03:43:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:43:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:43:06 INFO - Running setup.py install for requests 03:43:06 INFO - Running setup.py install for docopt 03:43:06 INFO - Successfully installed blobuploader requests docopt 03:43:06 INFO - Cleaning up... 03:43:06 INFO - Return code: 0 03:43:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:06 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')]} 03:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:06 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')]} 03:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:06 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:43:06 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 03:43:06 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 03:43:06 INFO - Using env: {'DISPLAY': ':0', 03:43:06 INFO - 'HOME': '/home/cltbld', 03:43:06 INFO - 'LANG': 'en_US.UTF-8', 03:43:06 INFO - 'LANGUAGE': 'en_US:en', 03:43:06 INFO - 'LOGNAME': 'cltbld', 03:43:06 INFO - 'MAIL': '/var/mail/cltbld', 03:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:06 INFO - 'MOZ_NO_REMOTE': '1', 03:43:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:06 INFO - 'NO_EM_RESTART': '1', 03:43:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:06 INFO - 'PWD': '/builds/slave/test', 03:43:06 INFO - 'SHELL': '/bin/bash', 03:43:06 INFO - 'SHLVL': '1', 03:43:06 INFO - 'TERM': 'linux', 03:43:06 INFO - 'TMOUT': '86400', 03:43:06 INFO - 'USER': 'cltbld', 03:43:06 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:06 INFO - '_': '/tools/buildbot/bin/python'} 03:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:06 INFO - Running setup.py (path:/tmp/pip-ALB2Zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:06 INFO - Running setup.py (path:/tmp/pip-huSmW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:06 INFO - Running setup.py (path:/tmp/pip-J2DRJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:07 INFO - Running setup.py (path:/tmp/pip-FVOhU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:07 INFO - Running setup.py (path:/tmp/pip-E2LV9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:07 INFO - Running setup.py (path:/tmp/pip-fYEUne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:07 INFO - Running setup.py (path:/tmp/pip-LUPP0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:07 INFO - Running setup.py (path:/tmp/pip-ujVeYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:07 INFO - Running setup.py (path:/tmp/pip-bof7aW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:07 INFO - Running setup.py (path:/tmp/pip-5Qk3tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:07 INFO - Running setup.py (path:/tmp/pip-ZePt7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:07 INFO - Running setup.py (path:/tmp/pip-OK7rOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:07 INFO - Running setup.py (path:/tmp/pip-W0xw5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:07 INFO - Running setup.py (path:/tmp/pip-j5e_zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:07 INFO - Running setup.py (path:/tmp/pip-c1pjgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:07 INFO - Running setup.py (path:/tmp/pip-WUuOeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:07 INFO - Running setup.py (path:/tmp/pip-m3lXCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:43:08 INFO - Running setup.py install for manifestparser 03:43:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Running setup.py install for mozcrash 03:43:08 INFO - Running setup.py install for mozdebug 03:43:08 INFO - Running setup.py install for mozdevice 03:43:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Running setup.py install for mozfile 03:43:08 INFO - Running setup.py install for mozhttpd 03:43:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Running setup.py install for mozinfo 03:43:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Running setup.py install for mozInstall 03:43:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:43:08 INFO - Running setup.py install for mozleak 03:43:08 INFO - Running setup.py install for mozlog 03:43:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Running setup.py install for moznetwork 03:43:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Running setup.py install for mozprocess 03:43:09 INFO - Running setup.py install for mozprofile 03:43:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Running setup.py install for mozrunner 03:43:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Running setup.py install for mozscreenshot 03:43:09 INFO - Running setup.py install for moztest 03:43:09 INFO - Running setup.py install for mozversion 03:43:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:43:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:43:09 INFO - Cleaning up... 03:43:09 INFO - Return code: 0 03:43:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:09 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:43:09 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 03:43:09 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 03:43:09 INFO - Using env: {'DISPLAY': ':0', 03:43:09 INFO - 'HOME': '/home/cltbld', 03:43:09 INFO - 'LANG': 'en_US.UTF-8', 03:43:09 INFO - 'LANGUAGE': 'en_US:en', 03:43:09 INFO - 'LOGNAME': 'cltbld', 03:43:09 INFO - 'MAIL': '/var/mail/cltbld', 03:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:09 INFO - 'MOZ_NO_REMOTE': '1', 03:43:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:09 INFO - 'NO_EM_RESTART': '1', 03:43:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:09 INFO - 'PWD': '/builds/slave/test', 03:43:09 INFO - 'SHELL': '/bin/bash', 03:43:09 INFO - 'SHLVL': '1', 03:43:09 INFO - 'TERM': 'linux', 03:43:09 INFO - 'TMOUT': '86400', 03:43:09 INFO - 'USER': 'cltbld', 03:43:09 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:09 INFO - '_': '/tools/buildbot/bin/python'} 03:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:43:10 INFO - Running setup.py (path:/tmp/pip-7PDeez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:43:10 INFO - Running setup.py (path:/tmp/pip-59TbSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:43:10 INFO - Running setup.py (path:/tmp/pip-lNmYrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:43:10 INFO - Running setup.py (path:/tmp/pip-F72qO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:43:10 INFO - Running setup.py (path:/tmp/pip-h4V43S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:43:10 INFO - Running setup.py (path:/tmp/pip-XNVvf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:43:10 INFO - Running setup.py (path:/tmp/pip-DHHNDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:43:10 INFO - Running setup.py (path:/tmp/pip-i7Ncss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:43:10 INFO - Running setup.py (path:/tmp/pip-Q8QJEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:43:10 INFO - Running setup.py (path:/tmp/pip-aW0Y1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:43:10 INFO - Running setup.py (path:/tmp/pip-WGvoTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:43:10 INFO - Running setup.py (path:/tmp/pip-hRMAf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:43:10 INFO - Running setup.py (path:/tmp/pip-toPhpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:43:10 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)) 03:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:43:11 INFO - Running setup.py (path:/tmp/pip-vpZMn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:43:11 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)) 03:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:11 INFO - Running setup.py (path:/tmp/pip-bAge0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:43:11 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)) 03:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:43:11 INFO - Running setup.py (path:/tmp/pip-_h3Zka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:43:11 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)) 03:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:43:11 INFO - Running setup.py (path:/tmp/pip-cIVmbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:43:11 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)) 03:43:11 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)) 03:43:11 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)) 03:43:11 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)) 03:43:11 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)) 03:43:11 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)) 03:43:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:43:11 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 03:43:11 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 03:43:11 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 03:43:11 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 03:43:11 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 03:43:11 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 03:43:13 INFO - Downloading blessings-1.6.tar.gz 03:43:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:43:13 INFO - Installing collected packages: blessings 03:43:13 INFO - Running setup.py install for blessings 03:43:13 INFO - Successfully installed blessings 03:43:13 INFO - Cleaning up... 03:43:13 INFO - Return code: 0 03:43:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:43:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:13 INFO - Reading from file tmpfile_stdout 03:43:13 INFO - Current package versions: 03:43:13 INFO - argparse == 1.2.1 03:43:13 INFO - blessings == 1.6 03:43:13 INFO - blobuploader == 1.2.4 03:43:13 INFO - docopt == 0.6.1 03:43:13 INFO - functools32 == 3.2.3-2 03:43:13 INFO - jsonschema == 2.5.1 03:43:13 INFO - manifestparser == 1.1 03:43:13 INFO - mozInstall == 1.12 03:43:13 INFO - mozcrash == 0.17 03:43:13 INFO - mozdebug == 0.1 03:43:13 INFO - mozdevice == 0.48 03:43:13 INFO - mozfile == 1.2 03:43:13 INFO - mozhttpd == 0.7 03:43:13 INFO - mozinfo == 0.9 03:43:13 INFO - mozleak == 0.1 03:43:13 INFO - mozlog == 3.3 03:43:13 INFO - moznetwork == 0.27 03:43:13 INFO - mozprocess == 0.23 03:43:13 INFO - mozprofile == 0.28 03:43:13 INFO - mozrunner == 6.12 03:43:13 INFO - mozscreenshot == 0.1 03:43:13 INFO - mozsystemmonitor == 0.3 03:43:13 INFO - moztest == 0.7 03:43:13 INFO - mozversion == 1.4 03:43:13 INFO - psutil == 3.1.1 03:43:13 INFO - requests == 1.2.3 03:43:13 INFO - wsgiref == 0.1.2 03:43:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:43:13 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')]} 03:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:13 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')]} 03:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:13 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:43:13 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 03:43:13 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 03:43:13 INFO - Using env: {'DISPLAY': ':0', 03:43:13 INFO - 'HOME': '/home/cltbld', 03:43:13 INFO - 'LANG': 'en_US.UTF-8', 03:43:13 INFO - 'LANGUAGE': 'en_US:en', 03:43:13 INFO - 'LOGNAME': 'cltbld', 03:43:13 INFO - 'MAIL': '/var/mail/cltbld', 03:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:13 INFO - 'MOZ_NO_REMOTE': '1', 03:43:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:13 INFO - 'NO_EM_RESTART': '1', 03:43:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:13 INFO - 'PWD': '/builds/slave/test', 03:43:13 INFO - 'SHELL': '/bin/bash', 03:43:13 INFO - 'SHLVL': '1', 03:43:13 INFO - 'TERM': 'linux', 03:43:13 INFO - 'TMOUT': '86400', 03:43:13 INFO - 'USER': 'cltbld', 03:43:13 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:13 INFO - '_': '/tools/buildbot/bin/python'} 03:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 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)) 03:43:13 INFO - Cleaning up... 03:43:13 INFO - Return code: 0 03:43:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:43:13 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')]} 03:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:13 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')]} 03:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:43:13 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 0x1cdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0b400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, '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': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', '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 03:43:13 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 03:43:13 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 03:43:13 INFO - Using env: {'DISPLAY': ':0', 03:43:13 INFO - 'HOME': '/home/cltbld', 03:43:13 INFO - 'LANG': 'en_US.UTF-8', 03:43:13 INFO - 'LANGUAGE': 'en_US:en', 03:43:13 INFO - 'LOGNAME': 'cltbld', 03:43:13 INFO - 'MAIL': '/var/mail/cltbld', 03:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:13 INFO - 'MOZ_NO_REMOTE': '1', 03:43:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:13 INFO - 'NO_EM_RESTART': '1', 03:43:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:13 INFO - 'PWD': '/builds/slave/test', 03:43:13 INFO - 'SHELL': '/bin/bash', 03:43:13 INFO - 'SHLVL': '1', 03:43:13 INFO - 'TERM': 'linux', 03:43:13 INFO - 'TMOUT': '86400', 03:43:13 INFO - 'USER': 'cltbld', 03:43:13 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:13 INFO - '_': '/tools/buildbot/bin/python'} 03:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:43:14 INFO - Cleaning up... 03:43:14 INFO - Return code: 0 03:43:14 INFO - Running post-action listener: _resource_record_post_action 03:43:14 INFO - Running post-action listener: _start_resource_monitoring 03:43:14 INFO - Starting resource monitoring. 03:43:14 INFO - [mozharness: 2016-09-13 10:43:14.085679Z] Finished create-virtualenv step (success) 03:43:14 INFO - [mozharness: 2016-09-13 10:43:14.086111Z] Running install step. 03:43:14 INFO - Running pre-action listener: _resource_record_pre_action 03:43:14 INFO - Running main action method: install 03:43:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:43:14 INFO - Reading from file tmpfile_stdout 03:43:14 INFO - Detecting whether we're running mozinstall >=1.0... 03:43:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:43:14 INFO - Reading from file tmpfile_stdout 03:43:14 INFO - Output received: 03:43:14 INFO - Usage: mozinstall [options] installer 03:43:14 INFO - Options: 03:43:14 INFO - -h, --help show this help message and exit 03:43:14 INFO - -d DEST, --destination=DEST 03:43:14 INFO - Directory to install application into. [default: 03:43:14 INFO - "/builds/slave/test"] 03:43:14 INFO - --app=APP Application being installed. [default: firefox] 03:43:14 INFO - mkdir: /builds/slave/test/build/application 03:43:14 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'] 03:43:14 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 03:43:31 INFO - Reading from file tmpfile_stdout 03:43:31 INFO - Output received: 03:43:31 INFO - /builds/slave/test/build/application/firefox/firefox 03:43:31 INFO - Running post-action listener: _resource_record_post_action 03:43:31 INFO - [mozharness: 2016-09-13 10:43:31.119297Z] Finished install step (success) 03:43:31 INFO - [mozharness: 2016-09-13 10:43:31.119570Z] Running run-tests step. 03:43:31 INFO - Running pre-action listener: _resource_record_pre_action 03:43:31 INFO - Running main action method: run_tests 03:43:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:43:31 INFO - Python 2.7.3 03:43:31 INFO - Return code: 0 03:43:31 INFO - grabbing minidump binary from tooltool 03:43: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')]} 03:43:31 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 0x1f9a570>, '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 0x1f9b470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f9b900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:43:31 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 03:43:31 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 03:43:31 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 03:43:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:43:31 INFO - Return code: 0 03:43:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:43:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:43:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:43:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:43:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:43:31 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/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:43:31 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/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 03:43:31 INFO - Using env: {'DISPLAY': ':0', 03:43:31 INFO - 'HOME': '/home/cltbld', 03:43:31 INFO - 'LANG': 'en_US.UTF-8', 03:43:31 INFO - 'LANGUAGE': 'en_US:en', 03:43:31 INFO - 'LOGNAME': 'cltbld', 03:43:31 INFO - 'MAIL': '/var/mail/cltbld', 03:43:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:43:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:31 INFO - 'MOZ_NO_REMOTE': '1', 03:43:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:31 INFO - 'NO_EM_RESTART': '1', 03:43:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:31 INFO - 'PWD': '/builds/slave/test', 03:43:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:43:31 INFO - 'SHELL': '/bin/bash', 03:43:31 INFO - 'SHLVL': '1', 03:43:31 INFO - 'TERM': 'linux', 03:43:31 INFO - 'TMOUT': '86400', 03:43:31 INFO - 'USER': 'cltbld', 03:43:31 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288', 03:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:31 INFO - '_': '/tools/buildbot/bin/python'} 03:43:31 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/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 03:43:31 INFO - mozversion application_buildid: 20160913004005 03:43:31 INFO - mozversion application_changeset: 696a981b6d534a7015d6f2ba3d2379af620e9b26 03:43:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:43:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:43:31 INFO - mozversion application_name: Firefox 03:43:31 INFO - mozversion application_remotingname: firefox-dev 03:43:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:43:31 INFO - mozversion application_vendor: Mozilla 03:43:31 INFO - mozversion application_version: 50.0a2 03:43:31 INFO - mozversion platform_buildid: 20160913004005 03:43:31 INFO - mozversion platform_changeset: 696a981b6d534a7015d6f2ba3d2379af620e9b26 03:43:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:43:31 INFO - mozversion platform_version: 50.0a2 03:43:31 INFO - using testdate: 1473763411 03:43:31 INFO - actual date: 1473763411 03:43:31 INFO - starting webserver on 'localhost:60616' 03:43:31 INFO - SUITE-START | Running 2 tests 03:43:31 INFO - TEST-START | dromaeo_css 03:43:31 INFO - operating with platform_type : linux_ 03:43:31 INFO - Initialising browser for dromaeo_css test... 03:43:31 INFO - TEST-INFO | started process 1766 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9mUeJL/profile http://localhost:60616/getInfo.html) 03:43:35 INFO - PROCESS | 1766 | __metrics Screen width/height:1600/1200 03:43:35 INFO - PROCESS | 1766 | colorDepth:24 03:43:35 INFO - PROCESS | 1766 | Browser inner width/height: 1024/697 03:43:35 INFO - PROCESS | 1766 | __metrics 03:43:36 ERROR - PROCESS | 1766 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 03:43:37 INFO - TEST-INFO | 1766: exit 0 03:43:37 INFO - Browser initialized. 03:43:37 INFO - Running cycle 1/1 for dromaeo_css test... 03:43:37 INFO - TEST-INFO | started process 1888 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9mUeJL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:43:49 INFO - PROCESS | 1888 | RSS: Main: 153460736 03:43:49 INFO - PROCESS | 1888 | 03:44:39 INFO - PROCESS | 1888 | 1473763479002 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] 03:46:47 INFO - PROCESS | 1888 | Cycle 1(1): loaded http://localhost:60616/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60616/tests/dromaeo/cssquery-ext.html) 03:46:48 INFO - PROCESS | 1888 | RSS: Main: 159424512 03:46:48 INFO - PROCESS | 1888 | 03:49:50 INFO - PROCESS | 1888 | Cycle 1(1): loaded http://localhost:60616/tests/dromaeo/cssquery-ext.html (next: http://localhost:60616/tests/dromaeo/cssquery-jquery.html) 03:49:51 INFO - PROCESS | 1888 | RSS: Main: 155570176 03:49:51 INFO - PROCESS | 1888 | 03:52:53 INFO - PROCESS | 1888 | Cycle 1(1): loaded http://localhost:60616/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60616/tests/dromaeo/cssquery-mootools.html) 03:52:53 INFO - PROCESS | 1888 | RSS: Main: 156143616 03:52:53 INFO - PROCESS | 1888 | 03:55:55 INFO - PROCESS | 1888 | Cycle 1(1): loaded http://localhost:60616/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60616/tests/dromaeo/cssquery-prototype.html) 03:55:56 INFO - PROCESS | 1888 | RSS: Main: 155291648 03:55:56 INFO - PROCESS | 1888 | 03:58:58 INFO - PROCESS | 1888 | Cycle 1(1): loaded http://localhost:60616/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60616/tests/dromaeo/cssquery-yui.html) 03:58:59 INFO - PROCESS | 1888 | RSS: Main: 157327360 03:58:59 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | Cycle 1(1): loaded http://localhost:60616/tests/dromaeo/cssquery-yui.html (next: http://localhost:60616/tests/dromaeo/cssquery-dojo.html) 04:02:01 INFO - PROCESS | 1888 | RSS: Main: 157298688 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | __start_tp_report 04:02:01 INFO - PROCESS | 1888 | _x_x_mozilla_page_load 04:02:01 INFO - PROCESS | 1888 | _x_x_mozilla_page_load_details 04:02:01 INFO - PROCESS | 1888 | |i|pagename|runs| 04:02:01 INFO - PROCESS | 1888 | |0;dojo.html;3297.950530742039;3340.0647818610796;3347.046091863819;3352.296017836255;3363.4114030044752;5774.56690748194;5784.508316793078;5822.475977162055;5844.824655260349;5852.414758524145;5740;5801.390853960336;5831.416858314166;5839.591228613998;5851.531877449805;5251.133562962116;5301.416844147141;5305.0450918834595;5359.866003349928;5389.434109418514;5113.948860511385;5126.5642420394315;5159.948400516003;5196.558292545138;5255.553277971377;5205.505476979681;5220.92168617471;5283.498067683564;5289.153735402337;5296.967091417175;5346.331708536433;5347.572194224492;5356.1697936819855;5367.919481207785;5373.408925018284;4913.189323761575;5110.770015349318;5229.294045303874;5250.684958902478;5318.361796584396;5697.743601537941;5761.107028410603;5770.9422905770825;5790.7394167262155;5832.270966129234;6154.784582539634;6209.875802483925;6240.781572644935;6255.655938923357;6282.183316168915;5935.376785437553;6183.165272688175;6302.369763023661;6325.462335701471;6330.87338253237;5806.767729290823;5859.970700146472;5883.441073098049;5895.882082358329;5913.645181289136;6399;6436.839079022968;6454.580452270681;6498.447631951242;6513;5824.534037277008;6038.335783063947;6110.572259941761;6206.751729930752;6218.160548326008;6159.414855588712;6255.999040153553;6265.248170219603;6282.277538083087;6288.654124023131;6220.7511699532415;6228.003519436868;6233.937660623426;6239.126522286883;6287.811365658946;5866.765329386777;6115.908261376083;6247.9062814057825;6277.403646653561;6295.874082518324;4690;4731.858044258678;4735.147673418817;4757.405324334458;4757.809687612456;4208.305629571148;4223.049813791934;4228.04868904494;4245.724027938174;4248.1078973415315;5724.8282551523525;5889.617174883619;5954.4045595440975;6000.849978750566;6024.006039003603;6148.78479253224;6216.658083805434;6243.937560624427;6248.968755156286;6306.653134077578;6016.278046634432;6027.7287522061615;6027.758889644453;6028.969855150696;6045.72794224261;5174.870628234249;5317.946820531823;5395.21769343443;5395.865103372368;5404.29744133268;6372.617642941438;6511.316311787384;6543.574667646588;6550.246721627074;6563.343665633399;6269.686515674107;6285.497160227263;6289.9056514153235;6299.527535434952;6318.210223722035;6159.630422174684;6285.120083188449;6442.742290308335;6444.8388790280615;6546.214454265519;6068.120122582289;6151.6924153793025;6161.84595385119;6178.042403427476;6182.103594978793;6242.344553821783;6245.500359971101;6301.905471417841;6309.873802524015;6321.589096708699;5845.240118784531;5960.4635582797755;6128.785492507741;6132.601380910227;6151.415615516519;5947.197128387786;6019.72911219005;6094.8171554853425;6116.082587611894;6117.571769976059;6036.275646922398;6047.395260474093;6049.334573197033;6055.2430946131735;6055.364186760326;5894.587378883437;5998.130271110577;6054.666993315233;6102.603330783485;6156.1381406604005;5912.793052243323;6024.638521688713;6032.33644299118;6046.153538504701;6064.969675151596 04:02:01 INFO - PROCESS | 1888 | |1;ext.html;581.2617975171532;652.3606865272034;666.779962612338;667.546068673302;672.5829985409046;46229;47951.76024119879;48187.518124818744;48375;52314.73842630781;49450.50549494504;49802.25296620554;49926.50073499264;49944.50055499444;50009.749951250196;9239.861402078957;9295.163435290822;9341.53292335384;9344.43933363997;9384.906150938472;9130.223930965876;9252.67615633453;9259.861102083474;9263;9269.907300926972;9252.213561847251;9287.303452241109;9302.953485232565;9333.766655833624;9350.532473376337;9171.449713017208;9253.074692530761;9259.583318750672;9313.953430232874;9315.813683726356;9200.447973121634;9213.309001824822;9238;9278.072192780668;9285.860712089325;47295.054098918175;47683;47704.52295477036;47736.5226347739;47816.52183478155;3465.1856813648774;4024.5371782245124;4099.9180016399805;4163.292240319153;4166.791660416998;35509.46735798965;35805;35841.64158358435;36171;36565.26869462596;4740.454847692525;4758.428988521365;4760.880977975544;4814.223526728603;4842.055799119174;373259;374284.99999999726;375330;376035;376872.1156394228;66717.66641166763;67482.32517674859;67488;67503.66248168728;67587;39072.60927390747;40192.39711404331;40241.59758402379;69442;69618.3038169622;16741.414050508174;16816.663666726843;17014.234359453607;17058.14709264532;17092.0599367036;11862.110341724232;11913.821292680617;11938.94030529859;11950.760984780429;11991.400429978466;21124.260650877146;21198.36404907855;21365.35903922885;21393.39549533791;21531.67702484464;7627.275408836152;7627.809304767426;7802.492837965628;7896.486728362638;7931.722389716332;990.65327135502;1083.247143235444;1083.544911137324;1138.3056335635256;1170.449888552379;34078.829605852305;34935;34991.37606312351;35067.82466087653;35505.289894201975;1120.6694025262404;1128.644476989762;1151.1021403305267;1161.8257261410856;1186.8397766301612;15699.293531791096;18321.267149314146;18374.81625183758;18380.44858654216;18587.34944276944;653144;657032;657941;660107;661645;67576;68309.65845170742;68558.65720671465;69027.65486172638;69064.65467672629;1440.906341087826;1465.2527211122404;1477.2023107522032;1502.511683702801;1504.1877386211324;35661.82169089138;35731.285374292886;36031.45952810658;36637.08407289839;36683;59255;62632.37367626266;64893.35106648873;65178.34821651912;71406.50077247272;561266;561574;561847;561933;565475;49857.25214121864;50148.498515014384;50185.498145018086;50192;51135.48864511456;55594.72202638961;55962.440375597354;56159.43840561542;56270;58127.70936145461;45114;47163;47649.285260720426;48255.75872120617;48767.268490971954;48650.270245947046;48877.75561122314;49041.509584903695;49188.75405622949;49849.00301994012;45328.77335613301;46480;46853.76573117249;47441;49456.50543494663;39820.80089599534;41233;41581.37627935644;41852.37221441742;42040.78979605205;31269.687303127586;31431.371372572878;31535.21161970969;31604.36791264208;31779.04662860118 04:02:01 INFO - PROCESS | 1888 | |2;jquery.html;424.45457587000703;426.4243271583361;426.8292682926831;435.69283655023213;437.19774214316726;19451;21407.464813379673;21440.785592144093;22077.889610551923;22842.65736013957;21080.15679372823;21143.577128457422;21356.8932155339;21484.57030859382;21753.12987480503;5891.410858914106;6104.816855494342;6106.2977757558;6111.847203819895;6115.816525504242;5994.790182343597;6006.8498287542725;6013.609115407507;6051.878962420749;6066.211392518966;5853.941460585383;6007.128966299884;6019.14774544292;6027;6037.909431358511;5938.524918006549;5950.494207992304;5968.88062238755;5995.430434108753;6007.969960150215;5347.358317001946;5390.6226564140525;5396.811111611075;5423.593230507728;5426.375966763794;24799;25579.1047313345;25701.228963131136;25854;26056.566888821108;2519.962200566993;2611.451595165018;2624.094687332886;2983.806052606595;3265.96734032659;15737.68524629501;15773.211339432983;16007.159176694713;16206.270717817726;16435.753463698053;2071.782462841395;2141.379000089972;2156.4177672028463;2186.10369748404;2226.7884550967633;65739;84684;84863;88242.55878720629;89675;11273.661790146309;11495;11558.537658493566;11765.352905590104;11817.231879927776;16650.250738716786;16713.582160445843;16733.83266167347;16753.91623041877;16778.58053548671;8242.670293188325;8283.875741863756;8330.250277474936;8497.915020849712;8631.309495240492;7767.184445633127;7834.216578342121;7897.802554936173;7960.681572737141;8069.314108300743;7035.542689725152;7255.238199988925;7326.853462930818;7349.889751653729;7394.889076663854;2016.546277087673;2104.8000439958178;2130.6164890319583;2149.4518897681305;2155.461134716321;1005.0851931725887;1043.5773511727762;1072.3887384191091;1108.0969010256615;1111.827666711661;12374.195677281128;13184.208947463183;20958.16167353319;21166.365009049754;21631.45921351979;492.4066499867651;510.61288476372806;513.6661170239374;519.3300642171632;519.516849330119;6721.1598550181225;6985.89521157183;7047.400970917427;7061.964690176516;7072.681729322193;553448;570608;578378;579013;582264;99386;101063.98936010693;102692;105581.47209264059;105614;506.7744853540139;518.5255491225562;518.9247559103909;522.1358651431887;525.4587774592176;11034;11958.10314226454;12063.095267854771;12186;12289.815652765037;40102.59897400989;42441.37834570526;43950.34074488821;44416;44936.77531612321;52411.21383179179;60397.69801150966;60574.394256056876;61636.38363616307;63721;8986.86519702192;9310.394824336396;9314.115159059878;9321.580528876353;9630.229581633592;13073.084884058024;13618.455261789417;13707.657308567366;14065.578032659037;14361;12216.816747748613;12265.877341226473;12380.257184568954;12571.182873113585;12653.810192846931;11490;11699.2980421175;12252.264864108181;12482.875171248534;12761.425735841724;12668.873311266769;12834.229946203257;12897;12912;12973.610791676265;8400.915990840012;8972.506512141896;8979.10208979097;9148.908510915071;9629.085236902483;10295.588176472857;12107.031437485157;12609.243445393306;12630.242185468902;12673.619791406272 04:02:01 INFO - PROCESS | 1888 | |3;mootools.html;1583.8495342942417;1596.8882178247525;1811.9637607247855;1887.4526387347669;1930.8648394612383;8695.826083478327;9312.906870931289;9607.951960240207;10229.744256393595;10261.692149235516;9473.573689183986;9868.407895526274;9903.10872021518;9903.900960990388;9906.405615663065;2509.874506274688;2519.357563821223;2524.2301098165053;2526.2926380613367;2535.3915060385466;2481.6153496208026;2565.179142674345;2566.461043180925;2566.8203225774205;2572.536943350196;2532.7847132993625;2533.936651583707;2580.522603318392;2581.2514370832437;2585.9612105818333;2495.488424872906;2510.6108553174286;2519.407939134302;2520.483300923325;2521.621756736486;2443.303658457331;2460.2127319257843;2463.6058230683007;2464.470138920141;2499.4001439654535;9865.358751681119;9980.101790838859;9988.800223995479;10051.798964020678;10081.243906706954;5536.695481748502;5671.092625179993;5710.2576665033275;5710.942890571083;5715.914261286084;3485.3552092862647;3608.440691692792;3661.212839239576;3670.614585468514;3693.242885208526;1213.9210951288137;1225.105672858818;1231.5504840733067;1285.5372066056211;1304.3413076396455;14278.571642850731;14296.785548216785;14317.35571899267;14335.139891606434;14365.569032929028;4852.320675105488;5004.524570165829;5060.392752869606;5101.668391554537;5108.438071812097;12067.939660301818;12115;12142.514299427923;12149.939250303692;12164.452599633038;5716.57125715573;5738.340090889601;5746.741396637161;5751.568632352591;5753.51095156908;4634.536546345338;5204.687718736888;5237.790488380422;5246.160614301693;5315.255864179018;4430.867073987785;4500.684952053391;4575.267957126844;4576.496585375606;4598.632109431237;1423.9430422782973;1426.7931149983401;1427.3434220258798;1456.8980171388114;1461.926903654813;988.728099772554;1030.8505266736431;1061.527620209015;1070.347088276151;1083.263380900995;6657;6683.6658167091455;6759.357861003196;6773.45812335012;6854.9657251713425;486.0327947384679;492.05525391248887;501.4609295007881;506.3215291509395;507.2518035897096;4427.867163985085;4517.028838799673;4587.9541204588195;4593.885152871138;4654.488006319302;11998.460069296903;12175.939120304341;12199.329036902878;12332.136750427384;12443.875561244453;12224.449899754532;12254.632361029184;12260.509579616893;12299.262044277371;12314.692132696575;8213.83572328562;8414.242718155396;8522.147785221552;8740.912590874137;8745.606447709868;7003.089598352181;7093.858122837618;7178.641067946477;7195.13658361;7260;11168.385738784445;11454.255473394203;11623.76752464963;11650.88349116498;11695.415229238333;11522.942385288356;11523.8271425927;11530.135239856876;11773.705657358634;11826.172167948505;7917;8429.4942303462;8565.828683426422;8651.52416617092;8857.070007649105;9639.518024098626;9659.951700241734;9703.417794932328;9722.513874306398;9780.804383912424;8046.758597242092;8090.959545202236;8104.594770261346;8150.9184908150155;8323.708670196515;8404.117567655308;8533.146685331418;8533.786655333666;8538.359623028422;8655.87016194745;7846.254605812439;7851.651197068116;7865.370770338473;7902.960485197767;7964.2433968772875;5790.826275211751;5822.7670893163795;5850.561207909339;5940.079287710412;5958.344582096052;5709.600327977002;5717.285339332583;5874.765009399747;5890.705464726832;5902.675352855634 04:02:01 INFO - PROCESS | 1888 | |4;prototype.html;359.9316129935312;360.7186394612203;364.1715098151706;365.93230252403305;368.40870403003163;5869.677167755772;5897.911531327033;5898.262717160355;5919.437653422918;5931.970340148293;5566.582506312023;5745.712714364276;5768.711564421783;5802.564807639434;5808.680522571257;4498.145352383053;4503.459584849823;4509.391232183646;4524.411826462555;4535.637149028086;4534.526278959352;4537.2286711259;4543.727376357413;4560.224761790487;4593.425821772278;4406.5373135820755;4479.8432054878085;4551.886202844922;4552.135094332067;4569.702969306984;4426.605619229948;4444.6666500012625;4457.465104187502;4463.5982761551295;4465.888352791174;3922.921541569181;3940;3945.4279129526344;3954.8220330085214;3981.1042515434087;5526.806561770359;5631.493165615114;5690.174924635905;5719.59962802604;5722.54219662426;5094.4141423736;5175.508326708957;5183.611229157828;5186.3662946171835;5225.869353266161;5986.341502434729;6029.758809647609;6045.576809623328;6085.691576311115;6154.292256390528;4283.079137985348;4384.790566475807;4398.912021759547;4427.180971520279;4445.555444455561;6134.69326533676;6315.147455093549;6331.860265152317;6363.899913028689;6381.170447841843;4431.202383570923;4488.1248156609145;4502.71672573315;4521.321801729767;4540.5913467787395;4556.066006468666;4586.656000799887;4588.059447813191;4598.26427771555;4598.97700511502;5002.57478114357;5204.5576126028955;5205.765740541685;5232.843014709565;5247.790088396421;6119.959606866843;6159.5688301818445;6172.845678858064;6181.258249010148;6203.47270482005;1556.2141118734967;1571.5049759326005;1576.2197712132338;1584.8430645628755;1597.9201039948189;966.0194902174298;1003.6386900715737;1004.688546550557;1010.1767059846202;1022.1669339488293;5198.870028249248;5261.106391484745;5297.284866542967;5313.840584782462;5334.466553344634;5447.221916822449;5517.641353312102;5584.944150558524;5589.580781441408;5609;4921.409430868319;5236.319278493847;5258.50044245796;5298.337707786527;5344;2962.170592234178;2962.5852380666724;2966.821990680566;2982.89559865408;3000.5499175123905;5453.091418003746;5664.773409063674;5696.658200507983;5714.3999880012;5738;5535.59560870062;5572.972135139298;5597.944020559742;5608.2148499209325;5640.351359593699;5656.971715141398;5668.036433806263;5680.914786278209;5690.402507736628;5693.743781529841;5229.895402092013;5394.522164751648;5421.587393609806;5523.7790488380015;5559.416261292506;5417.404085550507;5425.647332923505;5428.348598168245;5437.918431223456;5458.836234912959;6170.9074363885475;6284.5600807943;6491.675416229265;6598.802035938929;6606.273309935807;4442.73343599385;4473.2842745160615;4474.9328760069275;4479.193745125778;4487.7980490877335;6265.27949285838;6296.496280297473;6352.523560732871;6420.26166990802;6462.644554549545;6154.076888466766;6177.752889884354;6206.610454974957;6220.222472190976;6251.937480625317;5846.94153058464;6160.13758073861;6176.158966875288;6183.505319574513;6193.349698281796;5951.404859514014;5992.550558708202;6052.364501727431;6069.939300606938;6106.755729770759;6215.533834962486;6242.406971337716;6255.24937007562;6259.78090766821;6293.465055470153;5767.365589785204;6032.728527216374;6074.483668888194;6114.174586430775;6126.663033533198 04:02:01 INFO - PROCESS | 1888 | |5;yui.html;813.5281536708708;819.4264015189369;831.4096991136292;836.360184458889;941.3551717073801;16934.322627094916;17793.932364058164;18060.63878722428;18083.367082152126;18091.547711307223;15107.169105699182;15434.69130617387;15442.305096270667;15476.76784848228;15536.456224032161;699.2552931128357;825.2696363718122;863.1066845814598;867.1285358214985;874.3049275825733;865.3164000439646;866.9306455483546;867.2021739999216;868.522312727997;869.2133619074748;906.112010229975;915.7069737683944;918.8621706744009;919.1681528216974;921.3274309753883;906.3293163059354;916.276141847946;916.5371487398854;916.5966974531218;917.9495127767971;672.6603065451965;674.578388507183;675.1122274209399;676.5636164673807;678.2877978123;14604.488842890449;14639.780403293958;14648.926755366261;14649.19429431381;14678.266086695623;15985.920070399574;16973.15134243283;16989.660206795794;17078.914605426893;17282.30870765168;12928.870711292955;13132.540361087411;13224.471021159143;13266.933665331708;13545.864541354558;3988.78061706606;3998.7600743955454;4044.69664775143;4109.527404348479;4115.9176816463805;87490;87911.56044219802;89017;90118.54940725255;91554.5422272891;12453.439595218237;12636.55772047974;12673;12779.913707334981;12886.613401597968;25720.228393148234;25903.74096259051;25921.22236332913;25984.74015259823;26063.478730425286;8696.826063478695;8739.43193692408;8925.776855578662;9020.097990201059;9223.53882305882;5008.17365134756;5040.49595040493;5075.213341932006;5112.488751124858;5213.947860521346;3816.8473261069803;3950.4469374287623;3967.1668949520345;3967.285888540033;3975.284448799244;638.113022898172;642.6304874697067;642.7846671365052;644.1979735229669;658.3844105761128;695.7425752471618;793.730131755206;794.6265255329986;803.5571861540967;829.1087081387533;10218;10631.893681063246;10972.122230221563;11010.669679909615;11313.83029254562;722.9855402892018;772.7836205862368;804.3886646148852;812.488132476536;839.0560619303284;5160.767765450489;5302.840914772563;5408.91886621701;5424.321959755031;5441.809536666197;101775;119547;120377;122742;133747.33126334305;101638;104907;105799;107342;109945;90525.54737226403;94867;95356;96666;98570.50714746524;10109.797804044025;10307.690769276935;10372.22208334393;10443.164546836388;10792.56829726802;48734;50904.74547627238;56394.43605563892;56981;57718;52751.88992440413;53251.733741331045;69129.23483060622;69234;70223.29776702372;13748.65628359299;14093.788593171315;14170.362333695215;14222.288885555889;14652.853471465149;15279.847201527842;15419.306131224346;15456.922715386801;15562.61093472627;15579.376824926876;14081.225532595805;14169.504067357593;14353.210573418562;14506.782398263824;15689.372425102869;14690.045147065406;14755.704885902436;14770.77843832322;14882.553523394316;15527.689446211238;14041.5085472008;14661.193634349787;14969.101853888802;15014.174220417544;15546.37814487408;11729.765404692029;11846.644600661994;11957.820632690691;12304.692382690504;12444.564440244549;12376.381180941096;12486.937565312115;12574.30841303737;12642.178258413174;12709.364531773559 04:02:01 INFO - PROCESS | 1888 | __end_tp_report 04:02:01 INFO - PROCESS | 1888 | __start_cc_report 04:02:01 INFO - PROCESS | 1888 | _x_x_mozilla_cycle_collect,351 04:02:01 INFO - PROCESS | 1888 | __end_cc_report 04:02:01 INFO - PROCESS | 1888 | __startTimestamp1473764521908__endTimestamp 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | ------- Summary: start ------- 04:02:01 INFO - PROCESS | 1888 | Number of tests: 6 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | [#0] dojo.html Cycles:165 Average:5782.54 Median:6027.73 stddev:674.93 (11.2%) stddev-sans-first:648.25 04:02:01 INFO - PROCESS | 1888 | Values: 3298.0 3340.1 3347.0 3352.3 3363.4 5774.6 5784.5 5822.5 5844.8 5852.4 5740.0 5801.4 5831.4 5839.6 5851.5 5251.1 5301.4 5305.0 5359.9 5389.4 5113.9 5126.6 5159.9 5196.6 5255.6 5205.5 5220.9 5283.5 5289.2 5297.0 5346.3 5347.6 5356.2 5367.9 5373.4 4913.2 5110.8 5229.3 5250.7 5318.4 5697.7 5761.1 5770.9 5790.7 5832.3 6154.8 6209.9 6240.8 6255.7 6282.2 5935.4 6183.2 6302.4 6325.5 6330.9 5806.8 5860.0 5883.4 5895.9 5913.6 6399.0 6436.8 6454.6 6498.4 6513.0 5824.5 6038.3 6110.6 6206.8 6218.2 6159.4 6256.0 6265.2 6282.3 6288.7 6220.8 6228.0 6233.9 6239.1 6287.8 5866.8 6115.9 6247.9 6277.4 6295.9 4690.0 4731.9 4735.1 4757.4 4757.8 4208.3 4223.0 4228.0 4245.7 4248.1 5724.8 5889.6 5954.4 6000.8 6024.0 6148.8 6216.7 6243.9 6249.0 6306.7 6016.3 6027.7 6027.8 6029.0 6045.7 5174.9 5317.9 5395.2 5395.9 5404.3 6372.6 6511.3 6543.6 6550.2 6563.3 6269.7 6285.5 6289.9 6299.5 6318.2 6159.6 6285.1 6442.7 6444.8 6546.2 6068.1 6151.7 6161.8 6178.0 6182.1 6242.3 6245.5 6301.9 6309.9 6321.6 5845.2 5960.5 6128.8 6132.6 6151.4 5947.2 6019.7 6094.8 6116.1 6117.6 6036.3 6047.4 6049.3 6055.2 6055.4 5894.6 5998.1 6054.7 6102.6 6156.1 5912.8 6024.6 6032.3 6046.2 6065.0 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | [#1] ext.html Cycles:180 Average:71096.08 Median:48221.64 stddev:144964.30 (300.6%) stddev-sans-first:145274.28 04:02:01 INFO - PROCESS | 1888 | Values: 581.3 652.4 666.8 667.5 672.6 46229.0 47951.8 48187.5 48375.0 52314.7 49450.5 49802.3 49926.5 49944.5 50009.7 9239.9 9295.2 9341.5 9344.4 9384.9 9130.2 9252.7 9259.9 9263.0 9269.9 9252.2 9287.3 9303.0 9333.8 9350.5 9171.4 9253.1 9259.6 9314.0 9315.8 9200.4 9213.3 9238.0 9278.1 9285.9 47295.1 47683.0 47704.5 47736.5 47816.5 3465.2 4024.5 4099.9 4163.3 4166.8 35509.5 35805.0 35841.6 36171.0 36565.3 4740.5 4758.4 4760.9 4814.2 4842.1 373259.0 374285.0 375330.0 376035.0 376872.1 66717.7 67482.3 67488.0 67503.7 67587.0 39072.6 40192.4 40241.6 69442.0 69618.3 16741.4 16816.7 17014.2 17058.1 17092.1 11862.1 11913.8 11938.9 11950.8 11991.4 21124.3 21198.4 21365.4 21393.4 21531.7 7627.3 7627.8 7802.5 7896.5 7931.7 990.7 1083.2 1083.5 1138.3 1170.4 34078.8 34935.0 34991.4 35067.8 35505.3 1120.7 1128.6 1151.1 1161.8 1186.8 15699.3 18321.3 18374.8 18380.4 18587.3 653144.0 657032.0 657941.0 660107.0 661645.0 67576.0 68309.7 68558.7 69027.7 69064.7 1440.9 1465.3 1477.2 1502.5 1504.2 35661.8 35731.3 36031.5 36637.1 36683.0 59255.0 62632.4 64893.4 65178.3 71406.5 561266.0 561574.0 561847.0 561933.0 565475.0 49857.3 50148.5 50185.5 50192.0 51135.5 55594.7 55962.4 56159.4 56270.0 58127.7 45114.0 47163.0 47649.3 48255.8 48767.3 48650.3 48877.8 49041.5 49188.8 49849.0 45328.8 46480.0 46853.8 47441.0 49456.5 39820.8 41233.0 41581.4 41852.4 42040.8 31269.7 31431.4 31535.2 31604.4 31779.0 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | [#2] jquery.html Cycles:180 Average:32079.52 Median:21432.92 stddev:94271.53 (439.8%) stddev-sans-first:94506.02 04:02:01 INFO - PROCESS | 1888 | Values: 424.5 426.4 426.8 435.7 437.2 19451.0 21407.5 21440.8 22077.9 22842.7 21080.2 21143.6 21356.9 21484.6 21753.1 5891.4 6104.8 6106.3 6111.8 6115.8 5994.8 6006.8 6013.6 6051.9 6066.2 5853.9 6007.1 6019.1 6027.0 6037.9 5938.5 5950.5 5968.9 5995.4 6008.0 5347.4 5390.6 5396.8 5423.6 5426.4 24799.0 25579.1 25701.2 25854.0 26056.6 2520.0 2611.5 2624.1 2983.8 3266.0 15737.7 15773.2 16007.2 16206.3 16435.8 2071.8 2141.4 2156.4 2186.1 2226.8 65739.0 84684.0 84863.0 88242.6 89675.0 11273.7 11495.0 11558.5 11765.4 11817.2 16650.3 16713.6 16733.8 16753.9 16778.6 8242.7 8283.9 8330.3 8497.9 8631.3 7767.2 7834.2 7897.8 7960.7 8069.3 7035.5 7255.2 7326.9 7349.9 7394.9 2016.5 2104.8 2130.6 2149.5 2155.5 1005.1 1043.6 1072.4 1108.1 1111.8 12374.2 13184.2 20958.2 21166.4 21631.5 492.4 510.6 513.7 519.3 519.5 6721.2 6985.9 7047.4 7062.0 7072.7 553448.0 570608.0 578378.0 579013.0 582264.0 99386.0 101064.0 102692.0 105581.5 105614.0 506.8 518.5 518.9 522.1 525.5 11034.0 11958.1 12063.1 12186.0 12289.8 40102.6 42441.4 43950.3 44416.0 44936.8 52411.2 60397.7 60574.4 61636.4 63721.0 8986.9 9310.4 9314.1 9321.6 9630.2 13073.1 13618.5 13707.7 14065.6 14361.0 12216.8 12265.9 12380.3 12571.2 12653.8 11490.0 11699.3 12252.3 12482.9 12761.4 12668.9 12834.2 12897.0 12912.0 12973.6 8400.9 8972.5 8979.1 9148.9 9629.1 10295.6 12107.0 12609.2 12630.2 12673.6 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | [#3] mootools.html Cycles:180 Average:6480.04 Median:4596.26 stddev:3789.89 (82.5%) stddev-sans-first:3782.66 04:02:01 INFO - PROCESS | 1888 | Values: 1583.8 1596.9 1812.0 1887.5 1930.9 8695.8 9312.9 9608.0 10229.7 10261.7 9473.6 9868.4 9903.1 9903.9 9906.4 2509.9 2519.4 2524.2 2526.3 2535.4 2481.6 2565.2 2566.5 2566.8 2572.5 2532.8 2533.9 2580.5 2581.3 2586.0 2495.5 2510.6 2519.4 2520.5 2521.6 2443.3 2460.2 2463.6 2464.5 2499.4 9865.4 9980.1 9988.8 10051.8 10081.2 5536.7 5671.1 5710.3 5710.9 5715.9 3485.4 3608.4 3661.2 3670.6 3693.2 1213.9 1225.1 1231.6 1285.5 1304.3 14278.6 14296.8 14317.4 14335.1 14365.6 4852.3 5004.5 5060.4 5101.7 5108.4 12067.9 12115.0 12142.5 12149.9 12164.5 5716.6 5738.3 5746.7 5751.6 5753.5 4634.5 5204.7 5237.8 5246.2 5315.3 4430.9 4500.7 4575.3 4576.5 4598.6 1423.9 1426.8 1427.3 1456.9 1461.9 988.7 1030.9 1061.5 1070.3 1083.3 6657.0 6683.7 6759.4 6773.5 6855.0 486.0 492.1 501.5 506.3 507.3 4427.9 4517.0 4588.0 4593.9 4654.5 11998.5 12175.9 12199.3 12332.1 12443.9 12224.4 12254.6 12260.5 12299.3 12314.7 8213.8 8414.2 8522.1 8740.9 8745.6 7003.1 7093.9 7178.6 7195.1 7260.0 11168.4 11454.3 11623.8 11650.9 11695.4 11522.9 11523.8 11530.1 11773.7 11826.2 7917.0 8429.5 8565.8 8651.5 8857.1 9639.5 9660.0 9703.4 9722.5 9780.8 8046.8 8091.0 8104.6 8150.9 8323.7 8404.1 8533.1 8533.8 8538.4 8655.9 7846.3 7851.7 7865.4 7903.0 7964.2 5790.8 5822.8 5850.6 5940.1 5958.3 5709.6 5717.3 5874.8 5890.7 5902.7 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | [#4] prototype.html Cycles:180 Average:4979.56 Median:5442.57 stddev:1447.01 (26.6%) stddev-sans-first:1408.92 04:02:01 INFO - PROCESS | 1888 | Values: 359.9 360.7 364.2 365.9 368.4 5869.7 5897.9 5898.3 5919.4 5932.0 5566.6 5745.7 5768.7 5802.6 5808.7 4498.1 4503.5 4509.4 4524.4 4535.6 4534.5 4537.2 4543.7 4560.2 4593.4 4406.5 4479.8 4551.9 4552.1 4569.7 4426.6 4444.7 4457.5 4463.6 4465.9 3922.9 3940.0 3945.4 3954.8 3981.1 5526.8 5631.5 5690.2 5719.6 5722.5 5094.4 5175.5 5183.6 5186.4 5225.9 5986.3 6029.8 6045.6 6085.7 6154.3 4283.1 4384.8 4398.9 4427.2 4445.6 6134.7 6315.1 6331.9 6363.9 6381.2 4431.2 4488.1 4502.7 4521.3 4540.6 4556.1 4586.7 4588.1 4598.3 4599.0 5002.6 5204.6 5205.8 5232.8 5247.8 6120.0 6159.6 6172.8 6181.3 6203.5 1556.2 1571.5 1576.2 1584.8 1597.9 966.0 1003.6 1004.7 1010.2 1022.2 5198.9 5261.1 5297.3 5313.8 5334.5 5447.2 5517.6 5584.9 5589.6 5609.0 4921.4 5236.3 5258.5 5298.3 5344.0 2962.2 2962.6 2966.8 2982.9 3000.5 5453.1 5664.8 5696.7 5714.4 5738.0 5535.6 5573.0 5597.9 5608.2 5640.4 5657.0 5668.0 5680.9 5690.4 5693.7 5229.9 5394.5 5421.6 5523.8 5559.4 5417.4 5425.6 5428.3 5437.9 5458.8 6170.9 6284.6 6491.7 6598.8 6606.3 4442.7 4473.3 4474.9 4479.2 4487.8 6265.3 6296.5 6352.5 6420.3 6462.6 6154.1 6177.8 6206.6 6220.2 6251.9 5846.9 6160.1 6176.2 6183.5 6193.3 5951.4 5992.6 6052.4 6069.9 6106.8 6215.5 6242.4 6255.2 6259.8 6293.5 5767.4 6032.7 6074.5 6114.2 6126.7 04:02:01 INFO - PROCESS | 1888 | 04:02:01 INFO - PROCESS | 1888 | [#5] yui.html Cycles:180 Average:22166.59 Median:3883.65 stddev:31629.94 (814.4%) stddev-sans-first:31678.03 04:02:01 INFO - PROCESS | 1888 | Values: 813.5 819.4 831.4 836.4 941.4 16934.3 17793.9 18060.6 18083.4 18091.5 15107.2 15434.7 15442.3 15476.8 15536.5 699.3 825.3 863.1 867.1 874.3 865.3 866.9 867.2 868.5 869.2 906.1 915.7 918.9 919.2 921.3 906.3 916.3 916.5 916.6 917.9 672.7 674.6 675.1 676.6 678.3 14604.5 14639.8 14648.9 14649.2 14678.3 15985.9 16973.2 16989.7 17078.9 17282.3 12928.9 13132.5 13224.5 13266.9 13545.9 3988.8 3998.8 4044.7 4109.5 4115.9 87490.0 87911.6 89017.0 90118.5 91554.5 12453.4 12636.6 12673.0 12779.9 12886.6 25720.2 25903.7 25921.2 25984.7 26063.5 8696.8 8739.4 8925.8 9020.1 9223.5 5008.2 5040.5 5075.2 5112.5 5213.9 3816.8 3950.4 3967.2 3967.3 3975.3 638.1 642.6 642.8 644.2 658.4 695.7 793.7 794.6 803.6 829.1 10218.0 10631.9 10972.1 11010.7 11313.8 723.0 772.8 804.4 812.5 839.1 5160.8 5302.8 5408.9 5424.3 5441.8 101775.0 119547.0 120377.0 122742.0 133747.3 101638.0 104907.0 105799.0 107342.0 109945.0 90525.5 94867.0 95356.0 96666.0 98570.5 10109.8 10307.7 10372.2 10443.2 10792.6 48734.0 50904.7 56394.4 56981.0 57718.0 52751.9 53251.7 69129.2 69234.0 70223.3 13748.7 14093.8 14170.4 14222.3 14652.9 15279.8 15419.3 15456.9 15562.6 15579.4 14081.2 14169.5 14353.2 14506.8 15689.4 14690.0 14755.7 14770.8 14882.6 15527.7 14041.5 14661.2 14969.1 15014.2 15546.4 11729.8 11846.6 11957.8 12304.7 12444.6 12376.4 12486.9 12574.3 12642.2 12709.4 04:02:01 INFO - PROCESS | 1888 | -------- Summary: end -------- 04:02:01 INFO - PROCESS | 1888 | 04:02:02 INFO - TEST-INFO | 1888: exit 0 04:02:02 INFO - TEST-OK | dromaeo_css | took 1111287ms 04:02:02 INFO - TEST-START | kraken 04:02:02 INFO - operating with platform_type : linux_ 04:02:02 INFO - Initialising browser for kraken test... 04:02:02 INFO - TEST-INFO | started process 2149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDFK8qE/profile http://localhost:60616/getInfo.html) 04:02:07 INFO - PROCESS | 2149 | __metrics Screen width/height:1600/1200 04:02:07 INFO - PROCESS | 2149 | colorDepth:24 04:02:07 INFO - PROCESS | 2149 | Browser inner width/height: 1024/697 04:02:07 INFO - PROCESS | 2149 | __metrics 04:02:07 ERROR - PROCESS | 2149 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 04:02:08 INFO - TEST-INFO | 2149: exit 0 04:02:08 INFO - Browser initialized. 04:02:08 INFO - Running cycle 1/1 for kraken test... 04:02:08 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDFK8qE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:02:20 INFO - PROCESS | 2275 | RSS: Main: 153468928 04:02:20 INFO - PROCESS | 2275 | 04:02:22 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60616/tests/kraken/driver.html?testName=audio-beat-detection) 04:02:23 INFO - PROCESS | 2275 | RSS: Main: 158867456 04:02:23 INFO - PROCESS | 2275 | 04:02:26 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60616/tests/kraken/driver.html?testName=audio-dft) 04:02:27 INFO - PROCESS | 2275 | RSS: Main: 157782016 04:02:27 INFO - PROCESS | 2275 | 04:02:29 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60616/tests/kraken/driver.html?testName=audio-fft) 04:02:30 INFO - PROCESS | 2275 | RSS: Main: 158199808 04:02:30 INFO - PROCESS | 2275 | 04:02:32 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60616/tests/kraken/driver.html?testName=audio-oscillator) 04:02:33 INFO - PROCESS | 2275 | RSS: Main: 157921280 04:02:33 INFO - PROCESS | 2275 | 04:02:35 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60616/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:02:36 INFO - PROCESS | 2275 | RSS: Main: 157818880 04:02:36 INFO - PROCESS | 2275 | 04:02:39 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60616/tests/kraken/driver.html?testName=imaging-darkroom) 04:02:39 INFO - PROCESS | 2275 | RSS: Main: 158154752 04:02:39 INFO - PROCESS | 2275 | 04:02:43 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60616/tests/kraken/driver.html?testName=imaging-desaturate) 04:02:43 INFO - PROCESS | 2275 | RSS: Main: 155754496 04:02:43 INFO - PROCESS | 2275 | 04:02:46 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60616/tests/kraken/driver.html?testName=json-parse-financial) 04:02:47 INFO - PROCESS | 2275 | RSS: Main: 157073408 04:02:47 INFO - PROCESS | 2275 | 04:02:48 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60616/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:02:49 INFO - PROCESS | 2275 | RSS: Main: 157913088 04:02:49 INFO - PROCESS | 2275 | 04:02:51 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:02:51 INFO - PROCESS | 2275 | RSS: Main: 158015488 04:02:51 INFO - PROCESS | 2275 | 04:02:53 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:02:54 INFO - PROCESS | 2275 | RSS: Main: 158773248 04:02:54 INFO - PROCESS | 2275 | 04:02:57 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:02:57 INFO - PROCESS | 2275 | RSS: Main: 158285824 04:02:57 INFO - PROCESS | 2275 | 04:03:00 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:03:00 INFO - PROCESS | 2275 | RSS: Main: 158216192 04:03:00 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:60616/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60616/tests/kraken/driver.html?testName=ai-astar) 04:03:02 INFO - PROCESS | 2275 | RSS: Main: 159916032 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | __start_tp_report 04:03:02 INFO - PROCESS | 2275 | _x_x_mozilla_page_load 04:03:02 INFO - PROCESS | 2275 | _x_x_mozilla_page_load_details 04:03:02 INFO - PROCESS | 2275 | |i|pagename|runs| 04:03:02 INFO - PROCESS | 2275 | |0;ai-astar;102;111;111;105;113;111;110;112;111;122 04:03:02 INFO - PROCESS | 2275 | |1;audio-beat-detection;143;115;141;177;117;145;142;123;182;119 04:03:02 INFO - PROCESS | 2275 | |2;audio-dft;162;164;165;164;162;163;162;163;163;160 04:03:02 INFO - PROCESS | 2275 | |3;audio-fft;75;75;76;185;77;75;75;75;168;77 04:03:02 INFO - PROCESS | 2275 | |4;audio-oscillator;84;84;152;83;85;83;150;84;83;139 04:03:02 INFO - PROCESS | 2275 | |5;imaging-gaussian-blur;117;118;117;117;117;117;118;117;118;118 04:03:02 INFO - PROCESS | 2275 | |6;imaging-darkroom;147;149;147;147;148;146;147;148;148;146 04:03:02 INFO - PROCESS | 2275 | |7;imaging-desaturate;98;98;95;102;95;98;100;95;95;96 04:03:02 INFO - PROCESS | 2275 | |8;json-parse-financial;55;51;51;50;52;50;51;50;52;54 04:03:02 INFO - PROCESS | 2275 | |9;json-stringify-tinderbox;58;59;59;59;63;59;59;60;61;59 04:03:02 INFO - PROCESS | 2275 | |10;stanford-crypto-aes;74;73;75;74;73;79;73;81;81;74 04:03:02 INFO - PROCESS | 2275 | |11;stanford-crypto-ccm;120;125;127;126;117;118;117;128;148;129 04:03:02 INFO - PROCESS | 2275 | |12;stanford-crypto-pbkdf2;153;151;158;153;165;156;156;152;150;156 04:03:02 INFO - PROCESS | 2275 | |13;stanford-crypto-sha256-iterative;62;63;62;62;61;63;62;61;60;63 04:03:02 INFO - PROCESS | 2275 | __end_tp_report 04:03:02 INFO - PROCESS | 2275 | __start_cc_report 04:03:02 INFO - PROCESS | 2275 | _x_x_mozilla_cycle_collect,877 04:03:02 INFO - PROCESS | 2275 | __end_cc_report 04:03:02 INFO - PROCESS | 2275 | __startTimestamp1473764582840__endTimestamp 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | ------- Summary: start ------- 04:03:02 INFO - PROCESS | 2275 | Number of tests: 14 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:5.20 (4.7%) stddev-sans-first:4.44 04:03:02 INFO - PROCESS | 2275 | Values: 102.0 111.0 111.0 105.0 113.0 111.0 110.0 112.0 111.0 122.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:142.50 stddev:23.70 (16.6%) stddev-sans-first:25.12 04:03:02 INFO - PROCESS | 2275 | Values: 143.0 115.0 141.0 177.0 117.0 145.0 142.0 123.0 182.0 119.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.40 (0.9%) stddev-sans-first:1.45 04:03:02 INFO - PROCESS | 2275 | Values: 162.0 164.0 165.0 164.0 162.0 163.0 162.0 163.0 163.0 160.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#3] audio-fft Cycles:10 Average:95.80 Median:75.00 stddev:42.73 (57.0%) stddev-sans-first:44.65 04:03:02 INFO - PROCESS | 2275 | Values: 75.0 75.0 76.0 185.0 77.0 75.0 75.0 75.0 168.0 77.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:83.50 stddev:30.75 (36.8%) stddev-sans-first:31.87 04:03:02 INFO - PROCESS | 2275 | Values: 84.0 84.0 152.0 83.0 85.0 83.0 150.0 84.0 83.0 139.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:03:02 INFO - PROCESS | 2275 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 118.0 118.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 04:03:02 INFO - PROCESS | 2275 | Values: 147.0 149.0 147.0 147.0 148.0 146.0 147.0 148.0 148.0 146.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:95.50 stddev:2.44 (2.6%) stddev-sans-first:2.57 04:03:02 INFO - PROCESS | 2275 | Values: 98.0 98.0 95.0 102.0 95.0 98.0 100.0 95.0 95.0 96.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:51.50 stddev:1.71 (3.3%) stddev-sans-first:1.30 04:03:02 INFO - PROCESS | 2275 | Values: 55.0 51.0 51.0 50.0 52.0 50.0 51.0 50.0 52.0 54.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:1.43 (2.4%) stddev-sans-first:1.39 04:03:02 INFO - PROCESS | 2275 | Values: 58.0 59.0 59.0 59.0 63.0 59.0 59.0 60.0 61.0 59.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:74.50 stddev:3.30 (4.4%) stddev-sans-first:3.44 04:03:02 INFO - PROCESS | 2275 | Values: 74.0 73.0 75.0 74.0 73.0 79.0 73.0 81.0 81.0 74.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:126.50 stddev:9.18 (7.3%) stddev-sans-first:9.52 04:03:02 INFO - PROCESS | 2275 | Values: 120.0 125.0 127.0 126.0 117.0 118.0 117.0 128.0 148.0 129.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.00 Median:156.00 stddev:4.35 (2.8%) stddev-sans-first:4.55 04:03:02 INFO - PROCESS | 2275 | Values: 153.0 151.0 158.0 153.0 165.0 156.0 156.0 152.0 150.0 156.0 04:03:02 INFO - PROCESS | 2275 | 04:03:02 INFO - PROCESS | 2275 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 04:03:02 INFO - PROCESS | 2275 | Values: 62.0 63.0 62.0 62.0 61.0 63.0 62.0 61.0 60.0 63.0 04:03:02 INFO - PROCESS | 2275 | -------- Summary: end -------- 04:03:02 INFO - PROCESS | 2275 | 04:03:03 INFO - TEST-INFO | 2275: exit 0 04:03:03 INFO - TEST-OK | kraken | took 61114ms 04:03:03 INFO - SUITE-END | took 1172s 04:03:04 INFO - Completed test suite (00:19:32) 04:03:04 INFO - javascript benchmark 04:03:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7802.255037792954, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.950530742039, 3340.0647818610796, 3347.046091863819, 3352.296017836255, 3363.4114030044752, 5774.56690748194, 5784.508316793078, 5822.475977162055, 5844.824655260349, 5852.414758524145, 5740.0, 5801.390853960336, 5831.416858314166, 5839.591228613998, 5851.531877449805, 5251.133562962116, 5301.416844147141, 5305.0450918834595, 5359.866003349928, 5389.434109418514, 5113.948860511385, 5126.5642420394315, 5159.948400516003, 5196.558292545138, 5255.553277971377, 5205.505476979681, 5220.92168617471, 5283.498067683564, 5289.153735402337, 5296.967091417175, 5346.331708536433, 5347.572194224492, 5356.1697936819855, 5367.919481207785, 5373.408925018284, 4913.189323761575, 5110.770015349318, 5229.294045303874, 5250.684958902478, 5318.361796584396, 5697.743601537941, 5761.107028410603, 5770.9422905770825, 5790.7394167262155, 5832.270966129234, 6154.784582539634, 6209.875802483925, 6240.781572644935, 6255.655938923357, 6282.183316168915, 5935.376785437553, 6183.165272688175, 6302.369763023661, 6325.462335701471, 6330.87338253237, 5806.767729290823, 5859.970700146472, 5883.441073098049, 5895.882082358329, 5913.645181289136, 6399.0, 6436.839079022968, 6454.580452270681, 6498.447631951242, 6513.0, 5824.534037277008, 6038.335783063947, 6110.572259941761, 6206.751729930752, 6218.160548326008, 6159.414855588712, 6255.999040153553, 6265.248170219603, 6282.277538083087, 6288.654124023131, 6220.7511699532415, 6228.003519436868, 6233.937660623426, 6239.126522286883, 6287.811365658946, 5866.765329386777, 6115.908261376083, 6247.9062814057825, 6277.403646653561, 6295.874082518324, 4690.0, 4731.858044258678, 4735.147673418817, 4757.405324334458, 4757.809687612456, 4208.305629571148, 4223.049813791934, 4228.04868904494, 4245.724027938174, 4248.1078973415315, 5724.8282551523525, 5889.617174883619, 5954.4045595440975, 6000.849978750566, 6024.006039003603, 6148.78479253224, 6216.658083805434, 6243.937560624427, 6248.968755156286, 6306.653134077578, 6016.278046634432, 6027.7287522061615, 6027.758889644453, 6028.969855150696, 6045.72794224261, 5174.870628234249, 5317.946820531823, 5395.21769343443, 5395.865103372368, 5404.29744133268, 6372.617642941438, 6511.316311787384, 6543.574667646588, 6550.246721627074, 6563.343665633399, 6269.686515674107, 6285.497160227263, 6289.9056514153235, 6299.527535434952, 6318.210223722035, 6159.630422174684, 6285.120083188449, 6442.742290308335, 6444.8388790280615, 6546.214454265519, 6068.120122582289, 6151.6924153793025, 6161.84595385119, 6178.042403427476, 6182.103594978793, 6242.344553821783, 6245.500359971101, 6301.905471417841, 6309.873802524015, 6321.589096708699, 5845.240118784531, 5960.4635582797755, 6128.785492507741, 6132.601380910227, 6151.415615516519, 5947.197128387786, 6019.72911219005, 6094.8171554853425, 6116.082587611894, 6117.571769976059, 6036.275646922398, 6047.395260474093, 6049.334573197033, 6055.2430946131735, 6055.364186760326, 5894.587378883437, 5998.130271110577, 6054.666993315233, 6102.603330783485, 6156.1381406604005, 5912.793052243323, 6024.638521688713, 6032.33644299118, 6046.153538504701, 6064.969675151596], "value": 5735.974855100138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.2617975171532, 652.3606865272034, 666.779962612338, 667.546068673302, 672.5829985409046, 46229.0, 47951.76024119879, 48187.518124818744, 48375.0, 52314.73842630781, 49450.50549494504, 49802.25296620554, 49926.50073499264, 49944.50055499444, 50009.749951250196, 9239.861402078957, 9295.163435290822, 9341.53292335384, 9344.43933363997, 9384.906150938472, 9130.223930965876, 9252.67615633453, 9259.861102083474, 9263.0, 9269.907300926972, 9252.213561847251, 9287.303452241109, 9302.953485232565, 9333.766655833624, 9350.532473376337, 9171.449713017208, 9253.074692530761, 9259.583318750672, 9313.953430232874, 9315.813683726356, 9200.447973121634, 9213.309001824822, 9238.0, 9278.072192780668, 9285.860712089325, 47295.054098918175, 47683.0, 47704.52295477036, 47736.5226347739, 47816.52183478155, 3465.1856813648774, 4024.5371782245124, 4099.9180016399805, 4163.292240319153, 4166.791660416998, 35509.46735798965, 35805.0, 35841.64158358435, 36171.0, 36565.26869462596, 4740.454847692525, 4758.428988521365, 4760.880977975544, 4814.223526728603, 4842.055799119174, 373259.0, 374284.99999999726, 375330.0, 376035.0, 376872.1156394228, 66717.66641166763, 67482.32517674859, 67488.0, 67503.66248168728, 67587.0, 39072.60927390747, 40192.39711404331, 40241.59758402379, 69442.0, 69618.3038169622, 16741.414050508174, 16816.663666726843, 17014.234359453607, 17058.14709264532, 17092.0599367036, 11862.110341724232, 11913.821292680617, 11938.94030529859, 11950.760984780429, 11991.400429978466, 21124.260650877146, 21198.36404907855, 21365.35903922885, 21393.39549533791, 21531.67702484464, 7627.275408836152, 7627.809304767426, 7802.492837965628, 7896.486728362638, 7931.722389716332, 990.65327135502, 1083.247143235444, 1083.544911137324, 1138.3056335635256, 1170.449888552379, 34078.829605852305, 34935.0, 34991.37606312351, 35067.82466087653, 35505.289894201975, 1120.6694025262404, 1128.644476989762, 1151.1021403305267, 1161.8257261410856, 1186.8397766301612, 15699.293531791096, 18321.267149314146, 18374.81625183758, 18380.44858654216, 18587.34944276944, 653144.0, 657032.0, 657941.0, 660107.0, 661645.0, 67576.0, 68309.65845170742, 68558.65720671465, 69027.65486172638, 69064.65467672629, 1440.906341087826, 1465.2527211122404, 1477.2023107522032, 1502.511683702801, 1504.1877386211324, 35661.82169089138, 35731.285374292886, 36031.45952810658, 36637.08407289839, 36683.0, 59255.0, 62632.37367626266, 64893.35106648873, 65178.34821651912, 71406.50077247272, 561266.0, 561574.0, 561847.0, 561933.0, 565475.0, 49857.25214121864, 50148.498515014384, 50185.498145018086, 50192.0, 51135.48864511456, 55594.72202638961, 55962.440375597354, 56159.43840561542, 56270.0, 58127.70936145461, 45114.0, 47163.0, 47649.285260720426, 48255.75872120617, 48767.268490971954, 48650.270245947046, 48877.75561122314, 49041.509584903695, 49188.75405622949, 49849.00301994012, 45328.77335613301, 46480.0, 46853.76573117249, 47441.0, 49456.50543494663, 39820.80089599534, 41233.0, 41581.37627935644, 41852.37221441742, 42040.78979605205, 31269.687303127586, 31431.371372572878, 31535.21161970969, 31604.36791264208, 31779.04662860118], "value": 22436.50620577409, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.45457587000703, 426.4243271583361, 426.8292682926831, 435.69283655023213, 437.19774214316726, 19451.0, 21407.464813379673, 21440.785592144093, 22077.889610551923, 22842.65736013957, 21080.15679372823, 21143.577128457422, 21356.8932155339, 21484.57030859382, 21753.12987480503, 5891.410858914106, 6104.816855494342, 6106.2977757558, 6111.847203819895, 6115.816525504242, 5994.790182343597, 6006.8498287542725, 6013.609115407507, 6051.878962420749, 6066.211392518966, 5853.941460585383, 6007.128966299884, 6019.14774544292, 6027.0, 6037.909431358511, 5938.524918006549, 5950.494207992304, 5968.88062238755, 5995.430434108753, 6007.969960150215, 5347.358317001946, 5390.6226564140525, 5396.811111611075, 5423.593230507728, 5426.375966763794, 24799.0, 25579.1047313345, 25701.228963131136, 25854.0, 26056.566888821108, 2519.962200566993, 2611.451595165018, 2624.094687332886, 2983.806052606595, 3265.96734032659, 15737.68524629501, 15773.211339432983, 16007.159176694713, 16206.270717817726, 16435.753463698053, 2071.782462841395, 2141.379000089972, 2156.4177672028463, 2186.10369748404, 2226.7884550967633, 65739.0, 84684.0, 84863.0, 88242.55878720629, 89675.0, 11273.661790146309, 11495.0, 11558.537658493566, 11765.352905590104, 11817.231879927776, 16650.250738716786, 16713.582160445843, 16733.83266167347, 16753.91623041877, 16778.58053548671, 8242.670293188325, 8283.875741863756, 8330.250277474936, 8497.915020849712, 8631.309495240492, 7767.184445633127, 7834.216578342121, 7897.802554936173, 7960.681572737141, 8069.314108300743, 7035.542689725152, 7255.238199988925, 7326.853462930818, 7349.889751653729, 7394.889076663854, 2016.546277087673, 2104.8000439958178, 2130.6164890319583, 2149.4518897681305, 2155.461134716321, 1005.0851931725887, 1043.5773511727762, 1072.3887384191091, 1108.0969010256615, 1111.827666711661, 12374.195677281128, 13184.208947463183, 20958.16167353319, 21166.365009049754, 21631.45921351979, 492.4066499867651, 510.61288476372806, 513.6661170239374, 519.3300642171632, 519.516849330119, 6721.1598550181225, 6985.89521157183, 7047.400970917427, 7061.964690176516, 7072.681729322193, 553448.0, 570608.0, 578378.0, 579013.0, 582264.0, 99386.0, 101063.98936010693, 102692.0, 105581.47209264059, 105614.0, 506.7744853540139, 518.5255491225562, 518.9247559103909, 522.1358651431887, 525.4587774592176, 11034.0, 11958.10314226454, 12063.095267854771, 12186.0, 12289.815652765037, 40102.59897400989, 42441.37834570526, 43950.34074488821, 44416.0, 44936.77531612321, 52411.21383179179, 60397.69801150966, 60574.394256056876, 61636.38363616307, 63721.0, 8986.86519702192, 9310.394824336396, 9314.115159059878, 9321.580528876353, 9630.229581633592, 13073.084884058024, 13618.455261789417, 13707.657308567366, 14065.578032659037, 14361.0, 12216.816747748613, 12265.877341226473, 12380.257184568954, 12571.182873113585, 12653.810192846931, 11490.0, 11699.2980421175, 12252.264864108181, 12482.875171248534, 12761.425735841724, 12668.873311266769, 12834.229946203257, 12897.0, 12912.0, 12973.610791676265, 8400.915990840012, 8972.506512141896, 8979.10208979097, 9148.908510915071, 9629.085236902483, 10295.588176472857, 12107.031437485157, 12609.243445393306, 12630.242185468902, 12673.619791406272], "value": 9374.630154519184, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.8495342942417, 1596.8882178247525, 1811.9637607247855, 1887.4526387347669, 1930.8648394612383, 8695.826083478327, 9312.906870931289, 9607.951960240207, 10229.744256393595, 10261.692149235516, 9473.573689183986, 9868.407895526274, 9903.10872021518, 9903.900960990388, 9906.405615663065, 2509.874506274688, 2519.357563821223, 2524.2301098165053, 2526.2926380613367, 2535.3915060385466, 2481.6153496208026, 2565.179142674345, 2566.461043180925, 2566.8203225774205, 2572.536943350196, 2532.7847132993625, 2533.936651583707, 2580.522603318392, 2581.2514370832437, 2585.9612105818333, 2495.488424872906, 2510.6108553174286, 2519.407939134302, 2520.483300923325, 2521.621756736486, 2443.303658457331, 2460.2127319257843, 2463.6058230683007, 2464.470138920141, 2499.4001439654535, 9865.358751681119, 9980.101790838859, 9988.800223995479, 10051.798964020678, 10081.243906706954, 5536.695481748502, 5671.092625179993, 5710.2576665033275, 5710.942890571083, 5715.914261286084, 3485.3552092862647, 3608.440691692792, 3661.212839239576, 3670.614585468514, 3693.242885208526, 1213.9210951288137, 1225.105672858818, 1231.5504840733067, 1285.5372066056211, 1304.3413076396455, 14278.571642850731, 14296.785548216785, 14317.35571899267, 14335.139891606434, 14365.569032929028, 4852.320675105488, 5004.524570165829, 5060.392752869606, 5101.668391554537, 5108.438071812097, 12067.939660301818, 12115.0, 12142.514299427923, 12149.939250303692, 12164.452599633038, 5716.57125715573, 5738.340090889601, 5746.741396637161, 5751.568632352591, 5753.51095156908, 4634.536546345338, 5204.687718736888, 5237.790488380422, 5246.160614301693, 5315.255864179018, 4430.867073987785, 4500.684952053391, 4575.267957126844, 4576.496585375606, 4598.632109431237, 1423.9430422782973, 1426.7931149983401, 1427.3434220258798, 1456.8980171388114, 1461.926903654813, 988.728099772554, 1030.8505266736431, 1061.527620209015, 1070.347088276151, 1083.263380900995, 6657.0, 6683.6658167091455, 6759.357861003196, 6773.45812335012, 6854.9657251713425, 486.0327947384679, 492.05525391248887, 501.4609295007881, 506.3215291509395, 507.2518035897096, 4427.867163985085, 4517.028838799673, 4587.9541204588195, 4593.885152871138, 4654.488006319302, 11998.460069296903, 12175.939120304341, 12199.329036902878, 12332.136750427384, 12443.875561244453, 12224.449899754532, 12254.632361029184, 12260.509579616893, 12299.262044277371, 12314.692132696575, 8213.83572328562, 8414.242718155396, 8522.147785221552, 8740.912590874137, 8745.606447709868, 7003.089598352181, 7093.858122837618, 7178.641067946477, 7195.13658361, 7260.0, 11168.385738784445, 11454.255473394203, 11623.76752464963, 11650.88349116498, 11695.415229238333, 11522.942385288356, 11523.8271425927, 11530.135239856876, 11773.705657358634, 11826.172167948505, 7917.0, 8429.4942303462, 8565.828683426422, 8651.52416617092, 8857.070007649105, 9639.518024098626, 9659.951700241734, 9703.417794932328, 9722.513874306398, 9780.804383912424, 8046.758597242092, 8090.959545202236, 8104.594770261346, 8150.9184908150155, 8323.708670196515, 8404.117567655308, 8533.146685331418, 8533.786655333666, 8538.359623028422, 8655.87016194745, 7846.254605812439, 7851.651197068116, 7865.370770338473, 7902.960485197767, 7964.2433968772875, 5790.826275211751, 5822.7670893163795, 5850.561207909339, 5940.079287710412, 5958.344582096052, 5709.600327977002, 5717.285339332583, 5874.765009399747, 5890.705464726832, 5902.675352855634], "value": 5059.6702223376515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.9316129935312, 360.7186394612203, 364.1715098151706, 365.93230252403305, 368.40870403003163, 5869.677167755772, 5897.911531327033, 5898.262717160355, 5919.437653422918, 5931.970340148293, 5566.582506312023, 5745.712714364276, 5768.711564421783, 5802.564807639434, 5808.680522571257, 4498.145352383053, 4503.459584849823, 4509.391232183646, 4524.411826462555, 4535.637149028086, 4534.526278959352, 4537.2286711259, 4543.727376357413, 4560.224761790487, 4593.425821772278, 4406.5373135820755, 4479.8432054878085, 4551.886202844922, 4552.135094332067, 4569.702969306984, 4426.605619229948, 4444.6666500012625, 4457.465104187502, 4463.5982761551295, 4465.888352791174, 3922.921541569181, 3940.0, 3945.4279129526344, 3954.8220330085214, 3981.1042515434087, 5526.806561770359, 5631.493165615114, 5690.174924635905, 5719.59962802604, 5722.54219662426, 5094.4141423736, 5175.508326708957, 5183.611229157828, 5186.3662946171835, 5225.869353266161, 5986.341502434729, 6029.758809647609, 6045.576809623328, 6085.691576311115, 6154.292256390528, 4283.079137985348, 4384.790566475807, 4398.912021759547, 4427.180971520279, 4445.555444455561, 6134.69326533676, 6315.147455093549, 6331.860265152317, 6363.899913028689, 6381.170447841843, 4431.202383570923, 4488.1248156609145, 4502.71672573315, 4521.321801729767, 4540.5913467787395, 4556.066006468666, 4586.656000799887, 4588.059447813191, 4598.26427771555, 4598.97700511502, 5002.57478114357, 5204.5576126028955, 5205.765740541685, 5232.843014709565, 5247.790088396421, 6119.959606866843, 6159.5688301818445, 6172.845678858064, 6181.258249010148, 6203.47270482005, 1556.2141118734967, 1571.5049759326005, 1576.2197712132338, 1584.8430645628755, 1597.9201039948189, 966.0194902174298, 1003.6386900715737, 1004.688546550557, 1010.1767059846202, 1022.1669339488293, 5198.870028249248, 5261.106391484745, 5297.284866542967, 5313.840584782462, 5334.466553344634, 5447.221916822449, 5517.641353312102, 5584.944150558524, 5589.580781441408, 5609.0, 4921.409430868319, 5236.319278493847, 5258.50044245796, 5298.337707786527, 5344.0, 2962.170592234178, 2962.5852380666724, 2966.821990680566, 2982.89559865408, 3000.5499175123905, 5453.091418003746, 5664.773409063674, 5696.658200507983, 5714.3999880012, 5738.0, 5535.59560870062, 5572.972135139298, 5597.944020559742, 5608.2148499209325, 5640.351359593699, 5656.971715141398, 5668.036433806263, 5680.914786278209, 5690.402507736628, 5693.743781529841, 5229.895402092013, 5394.522164751648, 5421.587393609806, 5523.7790488380015, 5559.416261292506, 5417.404085550507, 5425.647332923505, 5428.348598168245, 5437.918431223456, 5458.836234912959, 6170.9074363885475, 6284.5600807943, 6491.675416229265, 6598.802035938929, 6606.273309935807, 4442.73343599385, 4473.2842745160615, 4474.9328760069275, 4479.193745125778, 4487.7980490877335, 6265.27949285838, 6296.496280297473, 6352.523560732871, 6420.26166990802, 6462.644554549545, 6154.076888466766, 6177.752889884354, 6206.610454974957, 6220.222472190976, 6251.937480625317, 5846.94153058464, 6160.13758073861, 6176.158966875288, 6183.505319574513, 6193.349698281796, 5951.404859514014, 5992.550558708202, 6052.364501727431, 6069.939300606938, 6106.755729770759, 6215.533834962486, 6242.406971337716, 6255.24937007562, 6259.78090766821, 6293.465055470153, 5767.365589785204, 6032.728527216374, 6074.483668888194, 6114.174586430775, 6126.663033533198], "value": 4521.676915296853, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.5281536708708, 819.4264015189369, 831.4096991136292, 836.360184458889, 941.3551717073801, 16934.322627094916, 17793.932364058164, 18060.63878722428, 18083.367082152126, 18091.547711307223, 15107.169105699182, 15434.69130617387, 15442.305096270667, 15476.76784848228, 15536.456224032161, 699.2552931128357, 825.2696363718122, 863.1066845814598, 867.1285358214985, 874.3049275825733, 865.3164000439646, 866.9306455483546, 867.2021739999216, 868.522312727997, 869.2133619074748, 906.112010229975, 915.7069737683944, 918.8621706744009, 919.1681528216974, 921.3274309753883, 906.3293163059354, 916.276141847946, 916.5371487398854, 916.5966974531218, 917.9495127767971, 672.6603065451965, 674.578388507183, 675.1122274209399, 676.5636164673807, 678.2877978123, 14604.488842890449, 14639.780403293958, 14648.926755366261, 14649.19429431381, 14678.266086695623, 15985.920070399574, 16973.15134243283, 16989.660206795794, 17078.914605426893, 17282.30870765168, 12928.870711292955, 13132.540361087411, 13224.471021159143, 13266.933665331708, 13545.864541354558, 3988.78061706606, 3998.7600743955454, 4044.69664775143, 4109.527404348479, 4115.9176816463805, 87490.0, 87911.56044219802, 89017.0, 90118.54940725255, 91554.5422272891, 12453.439595218237, 12636.55772047974, 12673.0, 12779.913707334981, 12886.613401597968, 25720.228393148234, 25903.74096259051, 25921.22236332913, 25984.74015259823, 26063.478730425286, 8696.826063478695, 8739.43193692408, 8925.776855578662, 9020.097990201059, 9223.53882305882, 5008.17365134756, 5040.49595040493, 5075.213341932006, 5112.488751124858, 5213.947860521346, 3816.8473261069803, 3950.4469374287623, 3967.1668949520345, 3967.285888540033, 3975.284448799244, 638.113022898172, 642.6304874697067, 642.7846671365052, 644.1979735229669, 658.3844105761128, 695.7425752471618, 793.730131755206, 794.6265255329986, 803.5571861540967, 829.1087081387533, 10218.0, 10631.893681063246, 10972.122230221563, 11010.669679909615, 11313.83029254562, 722.9855402892018, 772.7836205862368, 804.3886646148852, 812.488132476536, 839.0560619303284, 5160.767765450489, 5302.840914772563, 5408.91886621701, 5424.321959755031, 5441.809536666197, 101775.0, 119547.0, 120377.0, 122742.0, 133747.33126334305, 101638.0, 104907.0, 105799.0, 107342.0, 109945.0, 90525.54737226403, 94867.0, 95356.0, 96666.0, 98570.50714746524, 10109.797804044025, 10307.690769276935, 10372.22208334393, 10443.164546836388, 10792.56829726802, 48734.0, 50904.74547627238, 56394.43605563892, 56981.0, 57718.0, 52751.88992440413, 53251.733741331045, 69129.23483060622, 69234.0, 70223.29776702372, 13748.65628359299, 14093.788593171315, 14170.362333695215, 14222.288885555889, 14652.853471465149, 15279.847201527842, 15419.306131224346, 15456.922715386801, 15562.61093472627, 15579.376824926876, 14081.225532595805, 14169.504067357593, 14353.210573418562, 14506.782398263824, 15689.372425102869, 14690.045147065406, 14755.704885902436, 14770.77843832322, 14882.553523394316, 15527.689446211238, 14041.5085472008, 14661.193634349787, 14969.101853888802, 15014.174220417544, 15546.37814487408, 11729.765404692029, 11846.644600661994, 11957.820632690691, 12304.692382690504, 12444.564440244549, 12376.381180941096, 12486.937565312115, 12574.30841303737, 12642.178258413174, 12709.364531773559], "value": 8172.220338908895, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1503.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 111.0, 105.0, 113.0, 111.0, 110.0, 112.0, 111.0, 122.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 141.0, 177.0, 117.0, 145.0, 142.0, 123.0, 182.0, 119.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 165.0, 164.0, 162.0, 163.0, 162.0, 163.0, 163.0, 160.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 185.0, 77.0, 75.0, 75.0, 75.0, 168.0, 77.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 152.0, 83.0, 85.0, 83.0, 150.0, 84.0, 83.0, 139.0], "value": 102.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 147.0, 147.0, 148.0, 146.0, 147.0, 148.0, 148.0, 146.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 95.0, 102.0, 95.0, 98.0, 100.0, 95.0, 95.0, 96.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 51.0, 50.0, 52.0, 50.0, 51.0, 50.0, 52.0, 54.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 63.0, 59.0, 59.0, 60.0, 61.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 74.0, 73.0, 79.0, 73.0, 81.0, 81.0, 74.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 127.0, 126.0, 117.0, 118.0, 117.0, 128.0, 148.0, 129.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 158.0, 153.0, 165.0, 156.0, 156.0, 152.0, 150.0, 156.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 61.0, 63.0, 62.0, 61.0, 60.0, 63.0], "value": 61.9, "unit": "score"}]}]} 04:03:04 INFO - Return code: 0 04:03:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:03:04 ERROR - # TBPL SUCCESS # 04:03:04 INFO - Running post-action listener: _resource_record_post_action 04:03:04 INFO - [mozharness: 2016-09-13 11:03:04.144873Z] Finished run-tests step (success) 04:03:04 INFO - Running post-run listener: _resource_record_post_run 04:03:04 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 327581696; Read time: 36; Write time: 349088 04:03:04 INFO - TinderboxPrint: CPU usage
13.3% 04:03:04 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 36 04:03:04 INFO - TinderboxPrint: I/O write bytes / time
327,581,696 / 349,088 04:03:04 INFO - TinderboxPrint: CPU idle
8,230.0 (86.7%) 04:03:04 INFO - TinderboxPrint: CPU user
1,204.6 (12.7%) 04:03:04 INFO - TinderboxPrint: Swap in / out
0 / 0 04:03:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79204352; Read time: 0; Write time: 79404 04:03:04 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 262144; Write bytes: 247881728; Read time: 20; Write time: 269472 04:03:04 INFO - Running post-run listener: _upload_blobber_files 04:03:04 INFO - Blob upload gear active. 04:03:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:03:04 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 04:03:04 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'] 04:03:04 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 04:03:05 INFO - (blobuploader) - INFO - Open directory for files ... 04:03:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:03:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:03:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:06 INFO - (blobuploader) - INFO - Done attempting. 04:03:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:03:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:03:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:09 INFO - (blobuploader) - INFO - Done attempting. 04:03:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:03:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:03:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:03:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:03:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:03:12 INFO - (blobuploader) - INFO - Done attempting. 04:03:12 INFO - (blobuploader) - INFO - Iteration through files over. 04:03:12 INFO - Return code: 0 04:03:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:03:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:03:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b229ac78ed89e3c7aa5a15c638cf3979f10fc5744ff6730c59ee81567ffac7d3361ffa79a38af0d944bf55d61ff6d8f31281b050022c046ec7c816be5ad4d2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0e7204d53588a2c5ab0601e07430e23fae1858a43a53d2347742d6185476da1cc3e23b59389f2747f90b292f2658ad984baac2274507d239f1035510d3d6a93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13906abd3185d6890f60582c9eb985ff8934dd5d4f04d06be7313cb60c93885a74d4f485e38abe1d791d17b77b2dcb275044ba0362a3849604ab917dd6e14c9c"} 04:03:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:03:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:03:12 INFO - Contents: 04:03:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b229ac78ed89e3c7aa5a15c638cf3979f10fc5744ff6730c59ee81567ffac7d3361ffa79a38af0d944bf55d61ff6d8f31281b050022c046ec7c816be5ad4d2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0e7204d53588a2c5ab0601e07430e23fae1858a43a53d2347742d6185476da1cc3e23b59389f2747f90b292f2658ad984baac2274507d239f1035510d3d6a93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13906abd3185d6890f60582c9eb985ff8934dd5d4f04d06be7313cb60c93885a74d4f485e38abe1d791d17b77b2dcb275044ba0362a3849604ab917dd6e14c9c"} 04:03:12 INFO - Running post-run listener: copy_logs_to_upload_dir 04:03:12 INFO - Copying logs to upload dir... 04:03:12 INFO - mkdir: /builds/slave/test/build/upload/logs 04:03:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.764366 ========= master_lag: 66.72 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 29 mins, 8 secs) (at 2016-09-13 04:04:18.692490) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:18.696062) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288 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/4b229ac78ed89e3c7aa5a15c638cf3979f10fc5744ff6730c59ee81567ffac7d3361ffa79a38af0d944bf55d61ff6d8f31281b050022c046ec7c816be5ad4d2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0e7204d53588a2c5ab0601e07430e23fae1858a43a53d2347742d6185476da1cc3e23b59389f2747f90b292f2658ad984baac2274507d239f1035510d3d6a93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13906abd3185d6890f60582c9eb985ff8934dd5d4f04d06be7313cb60c93885a74d4f485e38abe1d791d17b77b2dcb275044ba0362a3849604ab917dd6e14c9c"} build_url:https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/QcGS6s6gQmKPxEj3cJNb8g/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/4b229ac78ed89e3c7aa5a15c638cf3979f10fc5744ff6730c59ee81567ffac7d3361ffa79a38af0d944bf55d61ff6d8f31281b050022c046ec7c816be5ad4d2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0e7204d53588a2c5ab0601e07430e23fae1858a43a53d2347742d6185476da1cc3e23b59389f2747f90b292f2658ad984baac2274507d239f1035510d3d6a93", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13906abd3185d6890f60582c9eb985ff8934dd5d4f04d06be7313cb60c93885a74d4f485e38abe1d791d17b77b2dcb275044ba0362a3849604ab917dd6e14c9c"}' ========= master_lag: 0.95 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:19.658344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:19.658650) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1473758904.950939-1319352288 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 04:04:19.784549) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 04:04:19.784836) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 04:04:20.754039) ========= ========= Total master_lag: 67.85 =========