builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1472121050.83 results: success (0) buildid: 20160825004011 builduid: 38ad8e684d244b93ba6ba9a8305abdae revision: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.827142) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.827927) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.828223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.843841) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.844151) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-25 03:30:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-08-25 03:30:52 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134808 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.992621) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:50.993011) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020018 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.042598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.042943) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 --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 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 --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=6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-25 03:30:52,116 truncating revision to first 12 chars 2016-08-25 03:30:52,116 Setting DEBUG logging. 2016-08-25 03:30:52,117 attempt 1/10 2016-08-25 03:30:52,117 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/30b1e0880c0f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 03:30:52,365 unpacking tar archive at: mozilla-aurora-30b1e0880c0f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.520153 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.576365) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.576702) ========= script_repo_revision: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.577131) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.577407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 03:30:51.589772) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-25 03:30:51.590091) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:30:52 INFO - MultiFileLogger online at 20160825 03:30:52 in /builds/slave/test 03:30:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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:30:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:30:52 INFO - {'append_to_log': False, 03:30:52 INFO - 'base_work_dir': '/builds/slave/test', 03:30:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:30:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:30:52 INFO - 'branch': 'Mozilla-Aurora', 03:30:52 INFO - 'buildbot_json_path': 'buildprops.json', 03:30:52 INFO - 'config_files': ('talos/linux_config.py',), 03:30:52 INFO - 'default_actions': ('clobber', 03:30:52 INFO - 'read-buildbot-config', 03:30:52 INFO - 'download-and-extract', 03:30:52 INFO - 'populate-webroot', 03:30:52 INFO - 'create-virtualenv', 03:30:52 INFO - 'install', 03:30:52 INFO - 'run-tests'), 03:30:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:30:52 INFO - 'download_minidump_stackwalk': True, 03:30:52 INFO - 'download_symbols': 'ondemand', 03:30:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:30:52 INFO - 'tooltool.py': '/tools/tooltool.py', 03:30:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:30:52 INFO - '/tools/misc-python/virtualenv.py')}, 03:30:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:30:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:30:52 INFO - 'installer_path': 'installer.exe', 03:30:52 INFO - 'log_level': 'info', 03:30:52 INFO - 'log_name': 'talos', 03:30:52 INFO - 'log_to_console': True, 03:30:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:30:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:30:52 INFO - 'opt_config_files': (), 03:30:52 INFO - 'pip_index': False, 03:30:52 INFO - 'sps_profile': False, 03:30:52 INFO - 'sps_profile_interval': 0, 03:30:52 INFO - 'suite': 'dromaeojs', 03:30:52 INFO - 'system_bits': '32', 03:30:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:30:52 INFO - 'title': 'talos-linux64-ix-023', 03:30:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:30:52 INFO - 'use_talos_json': True, 03:30:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:30:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:30:52 INFO - 'work_dir': 'build'} 03:30:52 INFO - [mozharness: 2016-08-25 10:30:52.751368Z] Running clobber step. 03:30:52 INFO - Running pre-action listener: _resource_record_pre_action 03:30:52 INFO - Running main action method: clobber 03:30:52 INFO - rmtree: /builds/slave/test/build 03:30:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:30:53 INFO - Running post-action listener: _resource_record_post_action 03:30:53 INFO - [mozharness: 2016-08-25 10:30:53.227476Z] Finished clobber step (success) 03:30:53 INFO - [mozharness: 2016-08-25 10:30:53.227578Z] Running read-buildbot-config step. 03:30:53 INFO - Running pre-action listener: _resource_record_pre_action 03:30:53 INFO - Running main action method: read_buildbot_config 03:30:53 INFO - Using buildbot properties: 03:30:53 INFO - { 03:30:53 INFO - "project": "", 03:30:53 INFO - "product": "firefox", 03:30:53 INFO - "script_repo_revision": "production", 03:30:53 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:30:53 INFO - "repository": "", 03:30:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:30:53 INFO - "buildid": "20160825004011", 03:30:53 INFO - "stage_platform": "linux64-pgo", 03:30:53 INFO - "basedir": "/builds/slave/test", 03:30:53 INFO - "nightly_build": "True", 03:30:53 INFO - "buildnumber": 491, 03:30:53 INFO - "slavename": "talos-linux64-ix-023", 03:30:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:30:53 INFO - "platform": "ubuntu64_hw", 03:30:53 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:30:53 INFO - "branch": "mozilla-aurora", 03:30:53 INFO - "repo_path": "releases/mozilla-aurora", 03:30:53 INFO - "revision": "30b1e0880c0f629c19f19b83576e6f5bd4f72a15", 03:30:53 INFO - "pgo_build": "True", 03:30:53 INFO - "builduid": "38ad8e684d244b93ba6ba9a8305abdae", 03:30:53 INFO - "slavebuilddir": "test" 03:30:53 INFO - } 03:30:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:30:53 INFO - Running post-action listener: _resource_record_post_action 03:30:53 INFO - [mozharness: 2016-08-25 10:30:53.230102Z] Finished read-buildbot-config step (success) 03:30:53 INFO - [mozharness: 2016-08-25 10:30:53.230192Z] Running download-and-extract step. 03:30:53 INFO - Running pre-action listener: _resource_record_pre_action 03:30:53 INFO - Running main action method: download_and_extract 03:30:53 INFO - mkdir: /builds/slave/test/build/tests 03:30: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:30:53 INFO - https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:30:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:30:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:30:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:30:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:30:53 INFO - retry: Failed, sleeping 30 seconds before retrying 03:31:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:31:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:31:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:31:23 INFO - retry: Failed, sleeping 60 seconds before retrying 03:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:32:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:23 INFO - Caught exception: HTTP Error 404: Not Found 03:32:23 INFO - Caught exception: HTTP Error 404: Not Found 03:32:23 INFO - Caught exception: HTTP Error 404: Not Found 03:32:23 INFO - trying https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:23 INFO - Downloaded 1361 bytes. 03:32:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:23 INFO - Using the following test package requirements: 03:32:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:32:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:32:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:32:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'jsshell-linux-x86_64.zip'], 03:32:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:32:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:32:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:32:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:32:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:32:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:32:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:32:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:32:23 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:32:23 INFO - https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:32:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:32:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:32:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:32:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:32:23 INFO - retry: Failed, sleeping 30 seconds before retrying 03:32:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:32:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:32:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:32:53 INFO - retry: Failed, sleeping 60 seconds before retrying 03:33:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:33:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:33:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:33:54 INFO - Caught exception: HTTP Error 404: Not Found 03:33:54 INFO - Caught exception: HTTP Error 404: Not Found 03:33:54 INFO - Caught exception: HTTP Error 404: Not Found 03:33:54 INFO - trying https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:54 INFO - Downloading https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:33:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:33:54 INFO - Downloaded 21768819 bytes. 03:33:54 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:33:54 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:33:55 INFO - Return code: 0 03:33:55 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:33:55 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:33:55 INFO - https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:33:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:33:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:33:56 INFO - retry: Failed, sleeping 30 seconds before retrying 03:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:34:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:34:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:34:26 INFO - retry: Failed, sleeping 60 seconds before retrying 03:35:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:35:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:26 INFO - Caught exception: HTTP Error 404: Not Found 03:35:26 INFO - Caught exception: HTTP Error 404: Not Found 03:35:26 INFO - Caught exception: HTTP Error 404: Not Found 03:35:26 INFO - trying https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:26 INFO - Downloading https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:26 INFO - Downloaded 21768819 bytes. 03:35:26 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:35:26 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:35:28 INFO - Return code: 0 03:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:35:28 INFO - https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:35:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:28 INFO - retry: Failed, sleeping 30 seconds before retrying 03:35:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:35:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:35:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:35:58 INFO - retry: Failed, sleeping 60 seconds before retrying 03:36:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:36:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:36:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:36:58 INFO - Caught exception: HTTP Error 404: Not Found 03:36:58 INFO - Caught exception: HTTP Error 404: Not Found 03:36:58 INFO - Caught exception: HTTP Error 404: Not Found 03:36:58 INFO - trying https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:58 INFO - Downloading https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:36:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:36:59 INFO - Downloaded 14413723 bytes. 03:36:59 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:36:59 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:37:00 INFO - Return code: 0 03:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:37:00 INFO - https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:37:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:37:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:37:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:37:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:37:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:37:00 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:37:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:37:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:30 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:38:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:38:30 INFO - Caught exception: HTTP Error 404: Not Found 03:38:30 INFO - Caught exception: HTTP Error 404: Not Found 03:38:30 INFO - Caught exception: HTTP Error 404: Not Found 03:38:30 INFO - trying https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:30 INFO - Downloading https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/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:38:31 INFO - Downloaded 63734112 bytes. 03:38:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:31 INFO - mkdir: /builds/slave/test/properties 03:38:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:38:31 INFO - Writing to file /builds/slave/test/properties/build_url 03:38:31 INFO - Contents: 03:38:31 INFO - build_url:https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:32 INFO - Running post-action listener: _resource_record_post_action 03:38:32 INFO - Running post-action listener: set_extra_try_arguments 03:38:32 INFO - [mozharness: 2016-08-25 10:38:32.171750Z] Finished download-and-extract step (success) 03:38:32 INFO - [mozharness: 2016-08-25 10:38:32.171982Z] Running populate-webroot step. 03:38:32 INFO - Running pre-action listener: _resource_record_pre_action 03:38:32 INFO - Running main action method: populate_webroot 03:38:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:38:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:38:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:38:32 INFO - '--tppagecycles', 03:38:32 INFO - '7'], 03:38:32 INFO - 'tests': ['tsvgm']}}, 03:38:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:38:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:38:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:38:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:38:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:38:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:38:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['damp', 'tps']}, 03:38:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'tests': ['damp', 'tps']}, 03:38:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['dromaeo_dom']}, 03:38:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:38:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['basic_compositor_video']}, 03:38:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:38:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['a11yr', 03:38:32 INFO - 'ts_paint', 03:38:32 INFO - 'tpaint', 03:38:32 INFO - 'sessionrestore', 03:38:32 INFO - 'sessionrestore_no_auto_restore', 03:38:32 INFO - 'tabpaint']}, 03:38:32 INFO - 'other-e10s': {'tests': ['a11yr', 03:38:32 INFO - 'ts_paint', 03:38:32 INFO - 'tpaint', 03:38:32 INFO - 'sessionrestore', 03:38:32 INFO - 'sessionrestore_no_auto_restore', 03:38:32 INFO - 'tabpaint']}, 03:38:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:38:32 INFO - 'ts_paint', 03:38:32 INFO - 'tpaint', 03:38:32 INFO - 'sessionrestore', 03:38:32 INFO - 'sessionrestore_no_auto_restore', 03:38:32 INFO - 'tabpaint']}, 03:38:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:38:32 INFO - 'ts_paint', 03:38:32 INFO - 'tpaint', 03:38:32 INFO - 'sessionrestore', 03:38:32 INFO - 'sessionrestore_no_auto_restore', 03:38:32 INFO - 'tabpaint']}, 03:38:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['a11yr', 03:38:32 INFO - 'ts_paint', 03:38:32 INFO - 'tpaint', 03:38:32 INFO - 'sessionrestore', 03:38:32 INFO - 'sessionrestore_no_auto_restore', 03:38:32 INFO - 'tabpaint']}, 03:38:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['a11yr', 03:38:32 INFO - 'ts_paint', 03:38:32 INFO - 'tpaint', 03:38:32 INFO - 'sessionrestore', 03:38:32 INFO - 'sessionrestore_no_auto_restore', 03:38:32 INFO - 'tabpaint']}, 03:38:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['tsvgx', 03:38:32 INFO - 'tsvgr_opacity', 03:38:32 INFO - 'tart', 03:38:32 INFO - 'tscrollx', 03:38:32 INFO - 'cart']}, 03:38:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:38:32 INFO - 'tsvgr_opacity', 03:38:32 INFO - 'tart', 03:38:32 INFO - 'tscrollx', 03:38:32 INFO - 'cart']}, 03:38:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'talos_options': ['--disable-e10s'], 03:38:32 INFO - 'tests': ['tp5o']}, 03:38:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'tests': ['tp5o']}, 03:38:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'talos_options': ['--disable-e10s', 03:38:32 INFO - '--xperf_path', 03:38:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:38:32 INFO - 'tests': ['tp5n']}, 03:38:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:38:32 INFO - 'talos_options': ['--xperf_path', 03:38:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:38:32 INFO - 'tests': ['tp5n']}}, 03:38:32 INFO - 'talos.zip': {'path': '', 03:38:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:38:32 INFO - Running post-action listener: _resource_record_post_action 03:38:32 INFO - [mozharness: 2016-08-25 10:38:32.193901Z] Finished populate-webroot step (success) 03:38:32 INFO - [mozharness: 2016-08-25 10:38:32.193991Z] Running create-virtualenv step. 03:38:32 INFO - Running pre-action listener: _resource_record_pre_action 03:38:32 INFO - Running main action method: create_virtualenv 03:38:32 INFO - Creating virtualenv /builds/slave/test/build/venv 03:38:32 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:38:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:38:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:38:32 INFO - Using real prefix '/usr' 03:38:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:38:32 INFO - Installing distribute.............................................................................................................................................................................................done. 03:38:34 INFO - Installing pip.................done. 03:38:34 INFO - Return code: 0 03:38:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:38:34 INFO - Using env: {'DISPLAY': ':0', 03:38:34 INFO - 'HOME': '/home/cltbld', 03:38:34 INFO - 'LANG': 'en_US.UTF-8', 03:38:34 INFO - 'LANGUAGE': 'en_US:en', 03:38:34 INFO - 'LOGNAME': 'cltbld', 03:38:34 INFO - 'MAIL': '/var/mail/cltbld', 03:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:34 INFO - 'MOZ_NO_REMOTE': '1', 03:38:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:34 INFO - 'NO_EM_RESTART': '1', 03:38:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:34 INFO - 'PWD': '/builds/slave/test', 03:38:34 INFO - 'SHELL': '/bin/bash', 03:38:34 INFO - 'SHLVL': '1', 03:38:34 INFO - 'TERM': 'linux', 03:38:34 INFO - 'TMOUT': '86400', 03:38:34 INFO - 'USER': 'cltbld', 03:38:34 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:34 INFO - '_': '/tools/buildbot/bin/python'} 03:38:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:34 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:38:34 INFO - Cleaning up... 03:38:34 INFO - Return code: 0 03:38:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:38:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:38:34 INFO - Using env: {'DISPLAY': ':0', 03:38:34 INFO - 'HOME': '/home/cltbld', 03:38:34 INFO - 'LANG': 'en_US.UTF-8', 03:38:34 INFO - 'LANGUAGE': 'en_US:en', 03:38:34 INFO - 'LOGNAME': 'cltbld', 03:38:34 INFO - 'MAIL': '/var/mail/cltbld', 03:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:34 INFO - 'MOZ_NO_REMOTE': '1', 03:38:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:34 INFO - 'NO_EM_RESTART': '1', 03:38:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:34 INFO - 'PWD': '/builds/slave/test', 03:38:34 INFO - 'SHELL': '/bin/bash', 03:38:34 INFO - 'SHLVL': '1', 03:38:34 INFO - 'TERM': 'linux', 03:38:34 INFO - 'TMOUT': '86400', 03:38:34 INFO - 'USER': 'cltbld', 03:38:34 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:34 INFO - '_': '/tools/buildbot/bin/python'} 03:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:35 INFO - Downloading/unpacking psutil>=3.1.1 03:38:35 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:38:35 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:38:35 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:38:35 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:38:35 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:38:35 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:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:38:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:38:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:38:37 INFO - Installing collected packages: psutil 03:38:37 INFO - Running setup.py install for psutil 03:38:37 INFO - building 'psutil._psutil_linux' extension 03:38:37 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:38:37 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:38:37 INFO - building 'psutil._psutil_posix' extension 03:38:37 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:38:37 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:38:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:38:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:38:37 INFO - Successfully installed psutil 03:38:37 INFO - Cleaning up... 03:38:37 INFO - Return code: 0 03:38:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:38:37 INFO - Using env: {'DISPLAY': ':0', 03:38:37 INFO - 'HOME': '/home/cltbld', 03:38:37 INFO - 'LANG': 'en_US.UTF-8', 03:38:37 INFO - 'LANGUAGE': 'en_US:en', 03:38:37 INFO - 'LOGNAME': 'cltbld', 03:38:37 INFO - 'MAIL': '/var/mail/cltbld', 03:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:37 INFO - 'MOZ_NO_REMOTE': '1', 03:38:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:37 INFO - 'NO_EM_RESTART': '1', 03:38:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:37 INFO - 'PWD': '/builds/slave/test', 03:38:37 INFO - 'SHELL': '/bin/bash', 03:38:37 INFO - 'SHLVL': '1', 03:38:37 INFO - 'TERM': 'linux', 03:38:37 INFO - 'TMOUT': '86400', 03:38:37 INFO - 'USER': 'cltbld', 03:38:37 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:37 INFO - '_': '/tools/buildbot/bin/python'} 03:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:38:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:38:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:38:39 INFO - Installing collected packages: mozsystemmonitor 03:38:39 INFO - Running setup.py install for mozsystemmonitor 03:38:39 INFO - Successfully installed mozsystemmonitor 03:38:39 INFO - Cleaning up... 03:38:40 INFO - Return code: 0 03:38:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:38:40 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:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:40 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:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:40 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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:40 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:38:40 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:38:40 INFO - Using env: {'DISPLAY': ':0', 03:38:40 INFO - 'HOME': '/home/cltbld', 03:38:40 INFO - 'LANG': 'en_US.UTF-8', 03:38:40 INFO - 'LANGUAGE': 'en_US:en', 03:38:40 INFO - 'LOGNAME': 'cltbld', 03:38:40 INFO - 'MAIL': '/var/mail/cltbld', 03:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:40 INFO - 'MOZ_NO_REMOTE': '1', 03:38:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:40 INFO - 'NO_EM_RESTART': '1', 03:38:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:40 INFO - 'PWD': '/builds/slave/test', 03:38:40 INFO - 'SHELL': '/bin/bash', 03:38:40 INFO - 'SHLVL': '1', 03:38:40 INFO - 'TERM': 'linux', 03:38:40 INFO - 'TMOUT': '86400', 03:38:40 INFO - 'USER': 'cltbld', 03:38:40 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:40 INFO - '_': '/tools/buildbot/bin/python'} 03:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:40 INFO - Downloading/unpacking jsonschema==2.5.1 03:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:38:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:42 INFO - Downloading functools32-3.2.3-2.tar.gz 03:38:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:38:42 INFO - warning: no files found matching '*.txt' 03:38:42 INFO - no previously-included directories found matching 'build' 03:38:42 INFO - no previously-included directories found matching 'dist' 03:38:42 INFO - no previously-included directories found matching '.git*' 03:38:42 INFO - Installing collected packages: jsonschema, functools32 03:38:42 INFO - Running setup.py install for functools32 03:38:42 INFO - warning: no files found matching '*.txt' 03:38:42 INFO - no previously-included directories found matching 'build' 03:38:42 INFO - no previously-included directories found matching 'dist' 03:38:42 INFO - no previously-included directories found matching '.git*' 03:38:42 INFO - Successfully installed jsonschema functools32 03:38:42 INFO - Cleaning up... 03:38:42 INFO - Return code: 0 03:38:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:38: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:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:42 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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:42 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:38:42 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:38:42 INFO - Using env: {'DISPLAY': ':0', 03:38:42 INFO - 'HOME': '/home/cltbld', 03:38:42 INFO - 'LANG': 'en_US.UTF-8', 03:38:42 INFO - 'LANGUAGE': 'en_US:en', 03:38:42 INFO - 'LOGNAME': 'cltbld', 03:38:42 INFO - 'MAIL': '/var/mail/cltbld', 03:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:42 INFO - 'MOZ_NO_REMOTE': '1', 03:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:42 INFO - 'NO_EM_RESTART': '1', 03:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:42 INFO - 'PWD': '/builds/slave/test', 03:38:42 INFO - 'SHELL': '/bin/bash', 03:38:42 INFO - 'SHLVL': '1', 03:38:42 INFO - 'TERM': 'linux', 03:38:42 INFO - 'TMOUT': '86400', 03:38:42 INFO - 'USER': 'cltbld', 03:38:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:42 INFO - '_': '/tools/buildbot/bin/python'} 03:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:38:42 INFO - Cleaning up... 03:38:42 INFO - Return code: 0 03:38:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:38: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:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:42 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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:42 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:38:42 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:38:42 INFO - Using env: {'DISPLAY': ':0', 03:38:42 INFO - 'HOME': '/home/cltbld', 03:38:42 INFO - 'LANG': 'en_US.UTF-8', 03:38:42 INFO - 'LANGUAGE': 'en_US:en', 03:38:42 INFO - 'LOGNAME': 'cltbld', 03:38:42 INFO - 'MAIL': '/var/mail/cltbld', 03:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:42 INFO - 'MOZ_NO_REMOTE': '1', 03:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:42 INFO - 'NO_EM_RESTART': '1', 03:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:42 INFO - 'PWD': '/builds/slave/test', 03:38:42 INFO - 'SHELL': '/bin/bash', 03:38:42 INFO - 'SHLVL': '1', 03:38:42 INFO - 'TERM': 'linux', 03:38:42 INFO - 'TMOUT': '86400', 03:38:42 INFO - 'USER': 'cltbld', 03:38:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:42 INFO - '_': '/tools/buildbot/bin/python'} 03:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:43 INFO - Downloading/unpacking blobuploader==1.2.4 03:38:43 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:38:43 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:38:43 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:38:43 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:38:43 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:38:43 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:38:44 INFO - Downloading blobuploader-1.2.4.tar.gz 03:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:38:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:38:44 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:38:44 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:38:44 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:38:44 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:38:44 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:38:44 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:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:38:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:38:45 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:38:45 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:38:45 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:38:45 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:38:45 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:38:45 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:38:45 INFO - Downloading docopt-0.6.1.tar.gz 03:38:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:38:45 INFO - Installing collected packages: blobuploader, requests, docopt 03:38:45 INFO - Running setup.py install for blobuploader 03:38:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:38:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:38:45 INFO - Running setup.py install for requests 03:38:46 INFO - Running setup.py install for docopt 03:38:46 INFO - Successfully installed blobuploader requests docopt 03:38:46 INFO - Cleaning up... 03:38:46 INFO - Return code: 0 03:38:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:46 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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:46 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:38:46 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:38:46 INFO - Using env: {'DISPLAY': ':0', 03:38:46 INFO - 'HOME': '/home/cltbld', 03:38:46 INFO - 'LANG': 'en_US.UTF-8', 03:38:46 INFO - 'LANGUAGE': 'en_US:en', 03:38:46 INFO - 'LOGNAME': 'cltbld', 03:38:46 INFO - 'MAIL': '/var/mail/cltbld', 03:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:46 INFO - 'MOZ_NO_REMOTE': '1', 03:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:46 INFO - 'NO_EM_RESTART': '1', 03:38:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:46 INFO - 'PWD': '/builds/slave/test', 03:38:46 INFO - 'SHELL': '/bin/bash', 03:38:46 INFO - 'SHLVL': '1', 03:38:46 INFO - 'TERM': 'linux', 03:38:46 INFO - 'TMOUT': '86400', 03:38:46 INFO - 'USER': 'cltbld', 03:38:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:46 INFO - '_': '/tools/buildbot/bin/python'} 03:38:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:46 INFO - Running setup.py (path:/tmp/pip-1Dt06q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:46 INFO - Running setup.py (path:/tmp/pip-uRASnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:46 INFO - Running setup.py (path:/tmp/pip-nLuqCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:46 INFO - Running setup.py (path:/tmp/pip-3MQEkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:46 INFO - Running setup.py (path:/tmp/pip-3j7NNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:46 INFO - Running setup.py (path:/tmp/pip-jk2IyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:46 INFO - Running setup.py (path:/tmp/pip-UJ1HYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:47 INFO - Running setup.py (path:/tmp/pip-dqwW2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:47 INFO - Running setup.py (path:/tmp/pip-z9LpiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:47 INFO - Running setup.py (path:/tmp/pip-bLF5ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:47 INFO - Running setup.py (path:/tmp/pip-eABvQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:47 INFO - Running setup.py (path:/tmp/pip-wQqJjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:47 INFO - Running setup.py (path:/tmp/pip-9CTMAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:47 INFO - Running setup.py (path:/tmp/pip-xQzdM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:47 INFO - Running setup.py (path:/tmp/pip-fiGzQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:47 INFO - Running setup.py (path:/tmp/pip-Th3Fok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:47 INFO - Running setup.py (path:/tmp/pip-DhQEcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:38:47 INFO - Running setup.py install for manifestparser 03:38:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:38:47 INFO - Running setup.py install for mozcrash 03:38:47 INFO - Running setup.py install for mozdebug 03:38:48 INFO - Running setup.py install for mozdevice 03:38:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Running setup.py install for mozfile 03:38:48 INFO - Running setup.py install for mozhttpd 03:38:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Running setup.py install for mozinfo 03:38:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Running setup.py install for mozInstall 03:38:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Running setup.py install for mozleak 03:38:48 INFO - Running setup.py install for mozlog 03:38:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Running setup.py install for moznetwork 03:38:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:38:48 INFO - Running setup.py install for mozprocess 03:38:48 INFO - Running setup.py install for mozprofile 03:38:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:38:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:38:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:38:49 INFO - Running setup.py install for mozrunner 03:38:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:38:49 INFO - Running setup.py install for mozscreenshot 03:38:49 INFO - Running setup.py install for moztest 03:38:49 INFO - Running setup.py install for mozversion 03:38:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:38:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:38:49 INFO - Cleaning up... 03:38:49 INFO - Return code: 0 03:38:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:49 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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:49 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:38:49 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:38:49 INFO - Using env: {'DISPLAY': ':0', 03:38:49 INFO - 'HOME': '/home/cltbld', 03:38:49 INFO - 'LANG': 'en_US.UTF-8', 03:38:49 INFO - 'LANGUAGE': 'en_US:en', 03:38:49 INFO - 'LOGNAME': 'cltbld', 03:38:49 INFO - 'MAIL': '/var/mail/cltbld', 03:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:49 INFO - 'MOZ_NO_REMOTE': '1', 03:38:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:49 INFO - 'NO_EM_RESTART': '1', 03:38:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:49 INFO - 'PWD': '/builds/slave/test', 03:38:49 INFO - 'SHELL': '/bin/bash', 03:38:49 INFO - 'SHLVL': '1', 03:38:49 INFO - 'TERM': 'linux', 03:38:49 INFO - 'TMOUT': '86400', 03:38:49 INFO - 'USER': 'cltbld', 03:38:49 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:49 INFO - '_': '/tools/buildbot/bin/python'} 03:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:49 INFO - Running setup.py (path:/tmp/pip-njtFLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:49 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:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:49 INFO - Running setup.py (path:/tmp/pip-3ROoek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:49 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:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:49 INFO - Running setup.py (path:/tmp/pip-HpTRzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:49 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:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:49 INFO - Running setup.py (path:/tmp/pip-CoOoii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:50 INFO - Running setup.py (path:/tmp/pip-QvGY0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:50 INFO - Running setup.py (path:/tmp/pip-YapzfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:50 INFO - Running setup.py (path:/tmp/pip-CW8ibB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:50 INFO - Running setup.py (path:/tmp/pip-xinm1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:50 INFO - Running setup.py (path:/tmp/pip-dk5S1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:50 INFO - Running setup.py (path:/tmp/pip-PmJxaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:50 INFO - Running setup.py (path:/tmp/pip-RabAB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:50 INFO - Running setup.py (path:/tmp/pip-6UX8QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:50 INFO - Running setup.py (path:/tmp/pip-V43mja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:50 INFO - Running setup.py (path:/tmp/pip-xfKUU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:50 INFO - Running setup.py (path:/tmp/pip-DK5TlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:50 INFO - Running setup.py (path:/tmp/pip-T_1wBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:50 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:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:50 INFO - Running setup.py (path:/tmp/pip-WR10jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:50 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:38:50 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:38:50 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:38:50 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:38:50 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:38:50 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:38:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38:51 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:38:51 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:38:51 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:38:51 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:38:51 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:38:51 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:38:52 INFO - Downloading blessings-1.6.tar.gz 03:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:38:52 INFO - Installing collected packages: blessings 03:38:52 INFO - Running setup.py install for blessings 03:38:53 INFO - Successfully installed blessings 03:38:53 INFO - Cleaning up... 03:38:53 INFO - Return code: 0 03:38:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:38:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:53 INFO - Reading from file tmpfile_stdout 03:38:53 INFO - Current package versions: 03:38:53 INFO - argparse == 1.2.1 03:38:53 INFO - blessings == 1.6 03:38:53 INFO - blobuploader == 1.2.4 03:38:53 INFO - docopt == 0.6.1 03:38:53 INFO - functools32 == 3.2.3-2 03:38:53 INFO - jsonschema == 2.5.1 03:38:53 INFO - manifestparser == 1.1 03:38:53 INFO - mozInstall == 1.12 03:38:53 INFO - mozcrash == 0.17 03:38:53 INFO - mozdebug == 0.1 03:38:53 INFO - mozdevice == 0.48 03:38:53 INFO - mozfile == 1.2 03:38:53 INFO - mozhttpd == 0.7 03:38:53 INFO - mozinfo == 0.9 03:38:53 INFO - mozleak == 0.1 03:38:53 INFO - mozlog == 3.3 03:38:53 INFO - moznetwork == 0.27 03:38:53 INFO - mozprocess == 0.23 03:38:53 INFO - mozprofile == 0.28 03:38:53 INFO - mozrunner == 6.12 03:38:53 INFO - mozscreenshot == 0.1 03:38:53 INFO - mozsystemmonitor == 0.3 03:38:53 INFO - moztest == 0.7 03:38:53 INFO - mozversion == 1.4 03:38:53 INFO - psutil == 3.1.1 03:38:53 INFO - requests == 1.2.3 03:38:53 INFO - wsgiref == 0.1.2 03:38:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38: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:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38:53 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 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38:53 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:38:53 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:38:53 INFO - Using env: {'DISPLAY': ':0', 03:38:53 INFO - 'HOME': '/home/cltbld', 03:38:53 INFO - 'LANG': 'en_US.UTF-8', 03:38:53 INFO - 'LANGUAGE': 'en_US:en', 03:38:53 INFO - 'LOGNAME': 'cltbld', 03:38:53 INFO - 'MAIL': '/var/mail/cltbld', 03:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:53 INFO - 'MOZ_NO_REMOTE': '1', 03:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:53 INFO - 'NO_EM_RESTART': '1', 03:38:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:53 INFO - 'PWD': '/builds/slave/test', 03:38:53 INFO - 'SHELL': '/bin/bash', 03:38:53 INFO - 'SHLVL': '1', 03:38:53 INFO - 'TERM': 'linux', 03:38:53 INFO - 'TMOUT': '86400', 03:38:53 INFO - 'USER': 'cltbld', 03:38:53 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:53 INFO - '_': '/tools/buildbot/bin/python'} 03:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 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:38:53 INFO - Cleaning up... 03:38:53 INFO - Return code: 0 03:38:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:38: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:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:38: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x123a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1398180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, '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': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', '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:38: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', 'jsonschema'] in /builds/slave/test/build 03:38: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 jsonschema 03:38:53 INFO - Using env: {'DISPLAY': ':0', 03:38:53 INFO - 'HOME': '/home/cltbld', 03:38:53 INFO - 'LANG': 'en_US.UTF-8', 03:38:53 INFO - 'LANGUAGE': 'en_US:en', 03:38:53 INFO - 'LOGNAME': 'cltbld', 03:38:53 INFO - 'MAIL': '/var/mail/cltbld', 03:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:53 INFO - 'MOZ_NO_REMOTE': '1', 03:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:53 INFO - 'NO_EM_RESTART': '1', 03:38:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:53 INFO - 'PWD': '/builds/slave/test', 03:38:53 INFO - 'SHELL': '/bin/bash', 03:38:53 INFO - 'SHLVL': '1', 03:38:53 INFO - 'TERM': 'linux', 03:38:53 INFO - 'TMOUT': '86400', 03:38:53 INFO - 'USER': 'cltbld', 03:38:53 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:53 INFO - '_': '/tools/buildbot/bin/python'} 03:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:38:53 INFO - Cleaning up... 03:38:53 INFO - Return code: 0 03:38:53 INFO - Running post-action listener: _resource_record_post_action 03:38:53 INFO - Running post-action listener: _start_resource_monitoring 03:38:53 INFO - Starting resource monitoring. 03:38:53 INFO - [mozharness: 2016-08-25 10:38:53.812534Z] Finished create-virtualenv step (success) 03:38:53 INFO - [mozharness: 2016-08-25 10:38:53.812970Z] Running install step. 03:38:53 INFO - Running pre-action listener: _resource_record_pre_action 03:38:53 INFO - Running main action method: install 03:38:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:54 INFO - Reading from file tmpfile_stdout 03:38:54 INFO - Detecting whether we're running mozinstall >=1.0... 03:38:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:38:54 INFO - Reading from file tmpfile_stdout 03:38:54 INFO - Output received: 03:38:54 INFO - Usage: mozinstall [options] installer 03:38:54 INFO - Options: 03:38:54 INFO - -h, --help show this help message and exit 03:38:54 INFO - -d DEST, --destination=DEST 03:38:54 INFO - Directory to install application into. [default: 03:38:54 INFO - "/builds/slave/test"] 03:38:54 INFO - --app=APP Application being installed. [default: firefox] 03:38:54 INFO - mkdir: /builds/slave/test/build/application 03:38:54 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:38:54 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:39:11 INFO - Reading from file tmpfile_stdout 03:39:11 INFO - Output received: 03:39:11 INFO - /builds/slave/test/build/application/firefox/firefox 03:39:11 INFO - Running post-action listener: _resource_record_post_action 03:39:11 INFO - [mozharness: 2016-08-25 10:39:11.036633Z] Finished install step (success) 03:39:11 INFO - [mozharness: 2016-08-25 10:39:11.036909Z] Running run-tests step. 03:39:11 INFO - Running pre-action listener: _resource_record_pre_action 03:39:11 INFO - Running main action method: run_tests 03:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:39:11 INFO - Python 2.7.3 03:39:11 INFO - Return code: 0 03:39:11 INFO - grabbing minidump binary from tooltool 03:39: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:39:11 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 0x13963b0>, '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 0x13972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1397740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:39:11 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:39:11 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:39:11 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:39:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:39:11 INFO - Return code: 0 03:39:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:39:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:39:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:39:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:39:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:39:11 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:39:11 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:39:11 INFO - Using env: {'DISPLAY': ':0', 03:39:11 INFO - 'HOME': '/home/cltbld', 03:39:11 INFO - 'LANG': 'en_US.UTF-8', 03:39:11 INFO - 'LANGUAGE': 'en_US:en', 03:39:11 INFO - 'LOGNAME': 'cltbld', 03:39:11 INFO - 'MAIL': '/var/mail/cltbld', 03:39:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:39:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:11 INFO - 'MOZ_NO_REMOTE': '1', 03:39:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:11 INFO - 'NO_EM_RESTART': '1', 03:39:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:11 INFO - 'PWD': '/builds/slave/test', 03:39:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:39:11 INFO - 'SHELL': '/bin/bash', 03:39:11 INFO - 'SHLVL': '1', 03:39:11 INFO - 'TERM': 'linux', 03:39:11 INFO - 'TMOUT': '86400', 03:39:11 INFO - 'USER': 'cltbld', 03:39:11 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251', 03:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:11 INFO - '_': '/tools/buildbot/bin/python'} 03:39:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:39:11 INFO - mozversion application_buildid: 20160825004011 03:39:11 INFO - mozversion application_changeset: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 03:39:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:39:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:39:11 INFO - mozversion application_name: Firefox 03:39:11 INFO - mozversion application_remotingname: firefox-dev 03:39:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:39:11 INFO - mozversion application_vendor: Mozilla 03:39:11 INFO - mozversion application_version: 50.0a2 03:39:11 INFO - mozversion platform_buildid: 20160825004011 03:39:11 INFO - mozversion platform_changeset: 30b1e0880c0f629c19f19b83576e6f5bd4f72a15 03:39:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:39:11 INFO - mozversion platform_version: 50.0a2 03:39:11 INFO - using testdate: 1472121551 03:39:11 INFO - actual date: 1472121551 03:39:11 INFO - starting webserver on 'localhost:38039' 03:39:11 INFO - SUITE-START | Running 2 tests 03:39:11 INFO - TEST-START | dromaeo_css 03:39:11 INFO - operating with platform_type : linux_ 03:39:11 INFO - Initialising browser for dromaeo_css test... 03:39:11 INFO - TEST-INFO | started process 10017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVWHmcD/profile http://localhost:38039/getInfo.html) 03:39:15 INFO - PROCESS | 10017 | __metrics Screen width/height:1600/1200 03:39:15 INFO - PROCESS | 10017 | colorDepth:24 03:39:15 INFO - PROCESS | 10017 | Browser inner width/height: 1024/697 03:39:15 INFO - PROCESS | 10017 | __metrics 03:39:16 INFO - PROCESS | 10017 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:39:16 INFO - PROCESS | 10017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:39:17 INFO - TEST-INFO | 10017: exit 0 03:39:17 INFO - Browser initialized. 03:39:17 INFO - Running cycle 1/1 for dromaeo_css test... 03:39:17 INFO - TEST-INFO | started process 10106 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVWHmcD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:39:28 INFO - PROCESS | 10106 | RSS: Main: 145489920 03:39:28 INFO - PROCESS | 10106 | 03:40:20 INFO - PROCESS | 10106 | 1472121620477 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:42:28 INFO - PROCESS | 10106 | Cycle 1(1): loaded http://localhost:38039/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38039/tests/dromaeo/cssquery-ext.html) 03:42:29 INFO - PROCESS | 10106 | RSS: Main: 229822464 03:42:29 INFO - PROCESS | 10106 | 03:45:31 INFO - PROCESS | 10106 | Cycle 1(1): loaded http://localhost:38039/tests/dromaeo/cssquery-ext.html (next: http://localhost:38039/tests/dromaeo/cssquery-jquery.html) 03:45:32 INFO - PROCESS | 10106 | RSS: Main: 236720128 03:45:32 INFO - PROCESS | 10106 | 03:48:34 INFO - PROCESS | 10106 | Cycle 1(1): loaded http://localhost:38039/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38039/tests/dromaeo/cssquery-mootools.html) 03:48:35 INFO - PROCESS | 10106 | RSS: Main: 205459456 03:48:35 INFO - PROCESS | 10106 | 03:51:36 INFO - PROCESS | 10106 | Cycle 1(1): loaded http://localhost:38039/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38039/tests/dromaeo/cssquery-prototype.html) 03:51:37 INFO - PROCESS | 10106 | RSS: Main: 189988864 03:51:37 INFO - PROCESS | 10106 | 03:54:40 INFO - PROCESS | 10106 | Cycle 1(1): loaded http://localhost:38039/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38039/tests/dromaeo/cssquery-yui.html) 03:54:40 INFO - PROCESS | 10106 | RSS: Main: 173649920 03:54:40 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | Cycle 1(1): loaded http://localhost:38039/tests/dromaeo/cssquery-yui.html (next: http://localhost:38039/tests/dromaeo/cssquery-dojo.html) 03:57:43 INFO - PROCESS | 10106 | RSS: Main: 266477568 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | __start_tp_report 03:57:43 INFO - PROCESS | 10106 | _x_x_mozilla_page_load 03:57:43 INFO - PROCESS | 10106 | _x_x_mozilla_page_load_details 03:57:43 INFO - PROCESS | 10106 | |i|pagename|runs| 03:57:43 INFO - PROCESS | 10106 | |0;dojo.html;3244.756643251756;3254.576905002349;3264.8530816113266;3281.34373125375;3289.0461766087838;5641.802536911209;5715.685637289947;5721.9999999999945;5726;5752.626079304842;5721.856953576163;5775.797847075353;5794.956907756613;5796.797112101077;5796.884062318751;5083.288339632454;5215.58275337973;5219.478052194788;5265.65773224741;5288.65623734456;5121.155009423441;5233.084210263208;5282.2076688496645;5284.762185701634;5296.549793267577;5000.074986127578;5042.672226305297;5054.064997975368;5097.49025097491;5098.694078355292;5161.5870730341485;5172.12073947428;5185.662931909414;5192.71440070796;5208.791648334062;5020.59835213182;5067.113255180366;5091.363579552556;5120.052790233819;5123.923141152885;5567.832965011056;5593.3847276799515;5610.887782244332;5623.831285061455;5661.716914154275;6118.8776224475705;6128.356522565111;6136.8158955230965;6144.078388241799;6145.446909778141;6069.848253793646;6076.7903407876165;6103.176071230416;6125.632462052291;6182.474489668383;5769.105788602773;5783.306003279633;5802.100674395476;5811.476967072899;5813.5349172066135;6034.215551978303;6313.842153946096;6327.588706734047;6332.525060620472;6334.429901308904;5935;5977.760889564369;6008.399160084044;6091.604045737102;6094.786682466092;6122.326544080148;6149.046897730859;6154.323024467305;6154.78458253959;6157.199564056644;5903.35063143054;6132.264128304632;6139.10982907476;6142.539309551801;6150.876982460415;5987.670678112746;5992.011318132547;6035.577509574287;6074.635521868702;6134.110553969649;4641.187792136363;4676.298555216745;4676.438827340741;4681.29780532916;4706.529347065266;4114.033202197481;4165.645920096765;4177.6866734995;4187.581241875788;4190.329547272483;5692.829215123553;5735.77056917727;5740.0815869460685;5794.507466865363;5830.47525722687;6099.786507472217;6106.596914875623;6107.7206961209795;6112.786052488142;6144.5084393248435;5627.549796016309;5766.4233576642;5776.191333213354;5779.739911703983;5790.623609465371;5189.714565698923;5225.059489291888;5228.189630607262;5283.445238250005;5300.761465734051;6227.875442491216;6259.593126446766;6271.62370257786;6271.749130034838;6273.560850740404;5917.970410147922;6076.088586712029;6080.7567697291615;6103.206583144163;6106.389361063858;6334.651594162366;6387.233531976192;6402.327755585597;6406.519511036784;6411.807645770635;6026.246719160105;6068;6069.0592958091565;6075.696215189135;6080.574359794772;5869.207656966255;6196.504279657706;6197.876042479215;6210.875782484415;6214.9689251553455;5913.142594323885;5970.0746384310505;5996.8800623988145;6071.605345652519;6104.084387341757;5972.850678733066;6011.549133814894;6018.51851851842;6023.698815059317;6027.336992930862;5712.714364281686;5931.1992880960515;5936.554758393224;5940.138679891478;5940.198073260054;5959.582829201914;6002.639841609517;6020.819375418744;6081.269411483649;6095.0752189950945;5909.70451477433;5916.940830591639;5918.17145599625;5919.733611987384;5946.940530594638 03:57:43 INFO - PROCESS | 10106 | |1;ext.html;620.8944479438497;644.7163248170805;664.7540410048281;678.1997243252963;687.2508965227901;35188.12029699256;47673.52326476734;48814;48841;48872.999999999956;49831.50168498323;50267.497325026736;50310.496895031036;50325.74837125818;50361;8490.78773030673;9240.630374785018;9384.577694003787;9386;9394.483303418308;11097.33415995039;11368.886311136906;11370.431478426088;11751.471183796706;11751.588694395698;11472.942635286854;11578.24741391811;11585.36280504572;11587.826182607268;11591.362475063868;11392.943035284812;11434.656960291204;11453.656390308304;11460.770784584345;11467.426628668616;11165.27425717334;11210.66368008961;11211.271267367594;11289.435528223556;11291.887081129247;47483;47718.761406193094;47767;47853.76073119647;47908.28137577904;3762.661360477543;3785.4511095890944;3825.6365645263654;3836.7058143532795;3914.823832927525;36123.65196262261;36128.458073128924;36207.81896090529;36504;38572.22855542874;4715.31627913954;4834.879128021792;4838.491958344358;4862.6839255448285;4931.926021109686;376848;378800;379346;379861.99999999726;380323;66839.66580167068;67102.66448667725;67279.3272067273;67285.96284222962;67332;40868;41636.58363416388;41734.582654173675;43975.560244397784;44084;18607.06964651762;18920.905395472935;18935.526611834815;18948;18954.62090758177;12525.624231272894;13765.862341376658;13877;14145.646358840906;14241;22013.559728805336;22081;22242.888785556293;22270.220542280942;22338.66492002621;7656.464047516732;7800.531968081819;7885.842283154419;7910.248526389983;8038.356931445471;947.6872632031414;1009.2733232072895;1017.3488967061087;1044.5612842606124;1064.5156453813497;36997.26005479929;36998.260034799154;37287.25425491475;37381.252374952346;37480.43779343312;1151.5911851292838;1175.2360965372577;1181.6986668399504;1193.9164258501996;1194.8028575285155;18939.621207575772;19350.90324548368;19424.805751942582;19521.414357569294;19693.70459443081;367260;373414;375639;519650;655425;67332.32667673359;68158.65920670365;68197.65901170562;68229;68410.31589684039;1342.8858547023417;1440.3878351700484;1446.1756798624892;1476.9926150369395;1495.461633811827;33767.662323376455;38161.98677149632;38366.232675346895;38506.422403664525;38624.613753862104;58422.12366814416;59255.70372148111;59265.40734592599;59337.703311483165;59346.10980835377;400782;404905;405765;406086;406689;28868.711312886604;31838.681613184497;32643.18392040123;32917.341653167285;33232.833835830665;55383.446165537825;55506;55641.72179139078;55642.72178639243;55654;45430.318545221184;46347;47168.764156179;47218.76390618025;48523.75738121287;45952.62258966779;48050;48226.27660585024;48264;49150;46098.53901460942;46344.536554634025;46653.300200497695;47353.52646473629;47518.287225690954;39779.40330895097;41565.584344156174;41602.791986041084;41609.16781664289;42487;30529.332015673124;30687.846560767946;30708.692913070583;31078.533821992234;31312.21719457032 03:57:43 INFO - PROCESS | 10106 | |2;jquery.html;398.79462077030314;409.3266576481686;420.77488543629164;429.2081110351402;441.89173652455156;15612.843871561281;20814.687779683318;20886.164553417846;21173.258935937247;22132.557348853014;20757.688634670492;21035.619690840427;21061;21247.893760531213;21296.4675883103;5837.795677151301;6002.369751176129;6013.037913933771;6043.6675982820925;6053.667048312341;5829.950608890398;5995.580309378345;6079.361667024965;6090.5736598438125;6092.025275955848;6185.505159587245;6189.38106189382;6194.907076393858;6196.659183744892;6204.069389591575;5988.2215312009375;6072.362401947776;6120.510359171256;6120.816375508742;6217.844553886144;5877.031194549788;5956.434138756811;5966.522678185735;5976.103584462366;5977.252843394575;14450.349734261983;14647.780283295759;14699.706005879929;14700.926495367456;15597.610059748484;2353.658719485682;2462.667539882129;2550.948981020369;2793.3435642623967;2913.257119434552;10543.209259305582;10690.572377104907;10954.780904381867;11082;11101.3894235817;1969.8030196980187;2090.937271881846;2115.280804526453;2142.271627646592;2158.6761985702115;63196.36803631951;66402.3359766401;75428.24571754267;76105.81418022007;88112.35662930059;10794.974477424632;11169.888301116885;11322.37726925027;11461.770764584662;11490.195686301879;14712.117272963442;14783.852161478248;14795.852041479662;14986.70026599462;15005.549833505012;8489.575521224038;8712.78218044554;8764.079771624016;8775.868361974575;8877.556122193864;8452.450590711584;8481.982162140583;8542.658293668308;8698.565071746387;8731.91268087311;4794.424669039738;4857.271409288635;4901.1913034349645;4984.152694042022;4996.975015124901;1986.7317912081974;2041.428400047989;2061.4743240473586;2144.624690679125;2148.656215005623;982.4007355513274;1015.1574193419444;1035.8549803027581;1038.6987773545586;1081.6322450366913;20809.687854682194;20944.952752362322;21335.67996480054;21450.463738406666;21606.135754569954;1017.1100287248657;1017.9032991865761;1040.4069680282166;1044.1438020823005;1108.3793075877534;11474.655760327203;11899.940500297444;11995.580154694544;12074;12107.939460302641;448727;448990;450363;539604;551277;94871.52564237134;97939.51030244946;97945;99444;99481.50259248658;1021.2187676427538;1049.7270709615402;1060.5333653192724;1067.146282973618;1071.4000159910474;18131.54671133232;18289.17698703535;18501.79738317005;18734;18841.90579047151;44114.33828492511;44459.777701111285;44755.776221118685;45529.54470455253;45860.31209531793;59911.101333479164;60984.69507652433;61484.385156149656;61559;61928.69035654793;7713.2286771322415;8255.917440825515;8259.752207433787;8696.565171741515;8697.173768491983;13207.141535800141;14241.64395890111;14482.34829432657;14620.41518339297;14843.109413435228;12232.938835305766;12540.937295313464;12558.372081396077;12825.102242842911;12860;12216.877831221575;12302.138850280395;12351.629451116482;12379.93810030944;13115.540956066492;12116.515339386326;12333.62999110028;12404.19372740769;12437.502499900265;12897.355132243538;8307.958460207661;8369.707060252862;8423;8596.699115530926;8756.693515726918;12266.202696824677;13015.804762928374;13065.934670326587;13089.08376413642;13097 03:57:43 INFO - PROCESS | 10106 | |3;mootools.html;1398.1052126638947;1499.467688970415;1570.0030480644791;1763.585557394012;1906.761654793151;8276.875846862295;9371.437713737172;9600.375975561583;9612.855807162898;9887.35732177408;9684.37051591646;9768.560414781334;9773.31586788925;9781.07079827417;9784.461854598012;2480.2187310997024;2514.358838496181;2517.697876254852;2518.8866501007496;2519.017583142576;2431.878406079698;2517.848929064253;2520.9117680881172;2522.382016405974;2524.3941454050987;2404.723456802473;2428.429319110008;2451.166603354859;2452.19077704358;2456.5578195924727;2410.505846301513;2428.9757102428925;2437.049550675229;2445.6331550267423;2449.865257410842;2384.9761502384927;2410.590199666061;2418.625113107471;2419.2863105383776;2443.2914454808083;9641.132298093205;9657.903420965771;9719.56261968213;9728.562214700285;9851.113399794052;5642.153676948449;5818.5926985111055;5861.618994765369;5890.057590785496;5898.292204935392;3806.809659517013;3811.9237615247816;3867.941980870289;3896.902577435558;3921.9215615687813;1288.4073326269927;1290.6644272489127;1291.8901893339078;1315.3160356614508;1338.738945905549;13361.13152645075;13489.123206991515;13504.459821607124;13507.189568625914;13515.121517101357;4674.485806561275;4864.70811751289;4874.147024270738;4967.453580106186;4971.403431588233;10940.4529773511;10952.671419857417;10954.616588419527;10958.671239862817;10968.561257549609;5690.487856092972;5700.743466544015;5704.401037890962;5705.030144875381;5706.857328566736;4747.335373047776;4793.784279707422;5225.817096401608;5230.006298803215;5342.973285133549;4146.574780860194;4151.875443736692;4213.094184750232;4215;4240.257954857949;1217.5677634439824;1221.7922953097816;1223.804191329383;1232.445399570179;1234.6481252842896;989.7327721515151;1049.559185142242;1088.9128869690405;1108.0747575774158;1109.4064675398622;6484.643344615997;6632.1046658701425;6826.726930922807;6911.89632155518;6942.479314051467;434.4222184494678;441.77248716910816;443.6140557714809;456.07416943604534;470.097412967104;4635.768211589474;4678.169871986937;4688.812447502061;4757.16749568831;4781.2350023996;12057.819132713017;12343.382830858422;12437.315947622787;12476.875231247754;12494.81257781134;11539.596114136133;11958.983486403578;12023.21849079807;12397;12418.689532761573;9034.186923176945;9262.861057084148;9272.536373181314;9291.488968106683;9294.395864268801;6716.630585317855;7060.399866011447;7060.43516518667;7088.2202957675645;7121.893171602534;11135.832962505407;11631.76736465249;11632.127590430582;11638.476268567765;11656.30062196271;11333.150013748836;11627;11799.941000294944;11801.645950621494;11867.821982670093;8780.121987801169;8795.736127916172;8798.516081615573;8877.822443551222;8951.41815781998;9578.616855325708;9625.614975400906;9636.325457217927;9644.614215431304;9653.324267301221;8498.362622803437;8622.568871556523;8634.179752923463;8660.35047371437;8682.218600326001;8161.102278749214;8185.959070204611;8453.535055572003;8467.872981905399;8515.105913878953;7728.8454230916195;7794.376449884109;7875.842483150191;8083.514989100673;8097.1497992712275;5837.474627283565;5903.763849446146;5963.522918166623;5981.0729336952845;6034.849128771816;5907.734151963258;5949.851253718692;5978.923793716998;6055.031195008777;6065.939340606537 03:57:43 INFO - PROCESS | 10106 | |4;prototype.html;353.08903030182125;353.9946900796487;361.21976530693706;369.4255432801992;370.71454979665674;5499.450054994503;5538.141588053853;5538.501534861862;5547.306586676666;5552.528035117015;5153.814622636786;5234.895302093965;5406.189071639256;5423.376311724153;5499.092649712803;4324.783760811963;4332.4151239582725;4334.851264414932;4350.564943505656;4361.6510679145595;4309.31051031835;4331.155424692186;4337.587929146726;4346.8043938022865;4355.344968911809;4118.938215926763;4252.532221455637;4283.400323954649;4312.994457577226;4318;4281.764502952336;4302.161078589661;4302.333138363543;4303.289957157065;4308.482982042144;3802.714796390254;3806.1436176859984;3807.485989391452;3826;3827.445020472044;5203.843884683428;5293.655912365684;5314.202869569557;5330.7334633268565;5343.572514198855;4904.773806548363;4918.409790825088;4918.483559226265;4919.843836698373;4929.679570827885;5724;5731;5733.369329373765;5739.196512488255;5756.453136951983;4058.6955978301744;4147.838605190552;4173.436586060873;4179.6029377209115;4188.853390131361;5848.41515848416;5913.201717768089;5924.822255332347;5926.7925622603;5957.225560677128;4237.533871274158;4257.169851878916;4258.361245813091;4267.274563324242;4289.399484072233;4265.253580623378;4287.828486860553;4321.524632290446;4361.890952726208;4365.759883206454;4865.489123642037;4871.123197824355;4873.220284754422;4890.902181956341;4898.510148985144;5720.170575266563;5771.567132465082;5780.277465316835;5802.65184088956;5820.650760954271;1410.2173293822027;1438.201798002098;1490.217635741223;1490.903091299062;1495.364470100203;974.4104816586005;988.7583533033358;992.6674564021134;996.7159359582485;1014.2089170447063;4900.162439085343;4901.583365413909;4926.236433352836;4932.975335123373;4980.477049909708;5113.846584602468;5133.358330208724;5156.613254005964;5160.716160611202;5161.845144645667;4696.459907110658;4847.909220425376;4871.244957031666;4893.972265824145;4900.215965445512;2729.6587926509187;2730.3720144366907;2735.8358498490156;2738.438620082878;2743.27303264635;5246.370435547682;5248.212768084742;5277.419483856773;5281.894362112736;5302.553340328817;4845.6608037437045;5174.97412512935;5194.974025129925;5202.60980426462;5211.1922651989;5226.216067589892;5239.253933437534;5272.736363181825;5274.5780337572905;5284.867878303073;5097.847064588068;5099.923501147411;5117.795288188579;5159;5164.870878228075;4799.712017278975;5028.673136246132;5040.873978150429;5130.076221416969;5132.769025393941;5255.343082114736;5263.710495922761;5274.525292723673;5322.627416080837;5323.840284791462;3889.8136068498247;3909.569947305737;3967.6311672472953;3968.900777480586;3971.92056158881;4930.6055515557955;5074.594032477319;5150.124478838531;5153.7165455900285;5166.689998600096;5556.527695145958;5629.915551266652;5762.1644861494015;5769.480746732815;5776.335721392094;5725.8568535786935;5727.570432217683;5771.7114144293455;5780.190773291826;5780.566457515619;5446.25007422649;5659.85850353728;5692.373838877637;5719.48524632785;5720.599558031065;5750.239884855209;5760.567957403296;5803.47768700819;5805.867855768085;5820.505257053029;5659.462351076641;5659.886802263849;5660.971695141498;5661.320641522879;5663.046249044073 03:57:43 INFO - PROCESS | 10106 | |5;yui.html;806.7579726082174;826.772637524681;867.262826597392;887.5429153985698;924.2421214604025;16151.838481615181;16381.83618163818;16979.91510042451;17004.234809433576;17056.91471542642;16180.433684821033;16258.75611865823;16277;16278.51164465068;16290.42983495578;749.8200431896361;889.9243564297043;891.1756625121752;895.6596493332524;913.3743385780729;898.2634239923266;899.7705585075796;900.0774206438421;900.2482926756167;900.621738869673;957.4016239850093;962.6101428921298;963.3401120232628;963.3641796414367;967.1344146988441;783.6865253898458;796.8525822722814;918.3296193778513;931.8835145606799;957.7749228931195;695.1762161838187;696.5159214346031;697.1146643762394;697.3340459860821;698.7868700046483;15721;15758.76361854573;15798.526044218692;15837.92081039599;15849.762253566205;14415.279236038261;14577.781333280009;14766.926165369212;14839.179827100766;15058.096514209074;13454;13516.526921557797;13587.184768913798;13916.234607096605;14274.785878211835;3888.747231429948;4257.276263035292;4263.253930562139;4397.274449715793;4478.171538265398;72629;82222.58888705578;82380;82618.58690706508;82687;11047.72380690489;11516.827247591293;11814.113941454254;12082.939585302192;12179.147459677915;22478.66282005771;22515.211967581385;22519.099236030375;22523.32430027102;22544.66183007256;8397.874031889527;8738.781530461662;8855.203031727175;8871.600777964877;9306.2089722373;4903.53416425439;4982.60139188864;5032.79868805251;5122.667026643257;5184.792608295701;3985.0834308109297;4162.292410290197;4165.166966606691;4173.290540608104;4337.82648694055;752.4657493179903;752.7553545097865;753.8982237397881;773.1958762886647;787.5589669784939;625.2871726232099;652.4812773844804;733.5965219129457;755.3353049316567;786.4966421490251;10329.483525823829;10797.568097276178;10845.349279043125;10905.727356815984;10944;677.2381071294793;679.3885503047297;760.7223363472293;779.080684791951;793.476305638276;5097.64316497849;5303.28405665238;5305.082045563207;5372.91940620891;5390.865228369322;126566;127089;128454.35772821076;128784;128974.35512822376;113285.43357283161;113493;113811;114237;114480.42759786148;70873.64563177254;95819;97524.51237743766;97786.51106744421;102421;10189.082982531607;10286.897131028594;10411.84382234252;10428.634997775041;10470.633527826794;55652.16521752096;56151.71924140353;56715.432845672665;57196.28411147672;57245.14132288102;51740.48259517357;52834.47165528449;65108;65565.34434655592;76081;13895.235762033186;14192.858071419567;14630.268486575842;14678.926605366905;14814.111153330834;16188.838111618734;16314.592135196715;16601.335946562485;17172.31310747606;17850.107494624957;14119.152850828983;14296.784773294386;14785.482508112165;15581.532554023397;16133.354665813236;12880.42038108306;14479.92760036193;15611.765823512429;15768.842311576738;16163.515094547183;14542.709145816812;14603.634909126931;14866.108033518023;14959.925200374364;15377.308021138851;10805.135589153006;11147.442627868737;11331.603393881174;11357;11462.885371146182;12848.42182101789;12962.235341512987;12971.41628626733;12990.155639883378;13330.200187988752 03:57:43 INFO - PROCESS | 10106 | __end_tp_report 03:57:43 INFO - PROCESS | 10106 | __start_cc_report 03:57:43 INFO - PROCESS | 10106 | _x_x_mozilla_cycle_collect,1023 03:57:43 INFO - PROCESS | 10106 | __end_cc_report 03:57:43 INFO - PROCESS | 10106 | __startTimestamp1472122663519__endTimestamp 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | ------- Summary: start ------- 03:57:43 INFO - PROCESS | 10106 | Number of tests: 6 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | [#0] dojo.html Cycles:165 Average:5674.46 Median:5918.17 stddev:657.74 (11.1%) stddev-sans-first:631.54 03:57:43 INFO - PROCESS | 10106 | Values: 3244.8 3254.6 3264.9 3281.3 3289.0 5641.8 5715.7 5722.0 5726.0 5752.6 5721.9 5775.8 5795.0 5796.8 5796.9 5083.3 5215.6 5219.5 5265.7 5288.7 5121.2 5233.1 5282.2 5284.8 5296.5 5000.1 5042.7 5054.1 5097.5 5098.7 5161.6 5172.1 5185.7 5192.7 5208.8 5020.6 5067.1 5091.4 5120.1 5123.9 5567.8 5593.4 5610.9 5623.8 5661.7 6118.9 6128.4 6136.8 6144.1 6145.4 6069.8 6076.8 6103.2 6125.6 6182.5 5769.1 5783.3 5802.1 5811.5 5813.5 6034.2 6313.8 6327.6 6332.5 6334.4 5935.0 5977.8 6008.4 6091.6 6094.8 6122.3 6149.0 6154.3 6154.8 6157.2 5903.4 6132.3 6139.1 6142.5 6150.9 5987.7 5992.0 6035.6 6074.6 6134.1 4641.2 4676.3 4676.4 4681.3 4706.5 4114.0 4165.6 4177.7 4187.6 4190.3 5692.8 5735.8 5740.1 5794.5 5830.5 6099.8 6106.6 6107.7 6112.8 6144.5 5627.5 5766.4 5776.2 5779.7 5790.6 5189.7 5225.1 5228.2 5283.4 5300.8 6227.9 6259.6 6271.6 6271.7 6273.6 5918.0 6076.1 6080.8 6103.2 6106.4 6334.7 6387.2 6402.3 6406.5 6411.8 6026.2 6068.0 6069.1 6075.7 6080.6 5869.2 6196.5 6197.9 6210.9 6215.0 5913.1 5970.1 5996.9 6071.6 6104.1 5972.9 6011.5 6018.5 6023.7 6027.3 5712.7 5931.2 5936.6 5940.1 5940.2 5959.6 6002.6 6020.8 6081.3 6095.1 5909.7 5916.9 5918.2 5919.7 5946.9 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | [#1] ext.html Cycles:180 Average:60708.37 Median:38598.42 stddev:110738.98 (286.9%) stddev-sans-first:110957.73 03:57:43 INFO - PROCESS | 10106 | Values: 620.9 644.7 664.8 678.2 687.3 35188.1 47673.5 48814.0 48841.0 48873.0 49831.5 50267.5 50310.5 50325.7 50361.0 8490.8 9240.6 9384.6 9386.0 9394.5 11097.3 11368.9 11370.4 11751.5 11751.6 11472.9 11578.2 11585.4 11587.8 11591.4 11392.9 11434.7 11453.7 11460.8 11467.4 11165.3 11210.7 11211.3 11289.4 11291.9 47483.0 47718.8 47767.0 47853.8 47908.3 3762.7 3785.5 3825.6 3836.7 3914.8 36123.7 36128.5 36207.8 36504.0 38572.2 4715.3 4834.9 4838.5 4862.7 4931.9 376848.0 378800.0 379346.0 379862.0 380323.0 66839.7 67102.7 67279.3 67286.0 67332.0 40868.0 41636.6 41734.6 43975.6 44084.0 18607.1 18920.9 18935.5 18948.0 18954.6 12525.6 13765.9 13877.0 14145.6 14241.0 22013.6 22081.0 22242.9 22270.2 22338.7 7656.5 7800.5 7885.8 7910.2 8038.4 947.7 1009.3 1017.3 1044.6 1064.5 36997.3 36998.3 37287.3 37381.3 37480.4 1151.6 1175.2 1181.7 1193.9 1194.8 18939.6 19350.9 19424.8 19521.4 19693.7 367260.0 373414.0 375639.0 519650.0 655425.0 67332.3 68158.7 68197.7 68229.0 68410.3 1342.9 1440.4 1446.2 1477.0 1495.5 33767.7 38162.0 38366.2 38506.4 38624.6 58422.1 59255.7 59265.4 59337.7 59346.1 400782.0 404905.0 405765.0 406086.0 406689.0 28868.7 31838.7 32643.2 32917.3 33232.8 55383.4 55506.0 55641.7 55642.7 55654.0 45430.3 46347.0 47168.8 47218.8 48523.8 45952.6 48050.0 48226.3 48264.0 49150.0 46098.5 46344.5 46653.3 47353.5 47518.3 39779.4 41565.6 41602.8 41609.2 42487.0 30529.3 30687.8 30708.7 31078.5 31312.2 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | [#2] jquery.html Cycles:180 Average:29296.05 Median:2153.67 stddev:80846.33 (3753.9%) stddev-sans-first:81044.01 03:57:43 INFO - PROCESS | 10106 | Values: 398.8 409.3 420.8 429.2 441.9 15612.8 20814.7 20886.2 21173.3 22132.6 20757.7 21035.6 21061.0 21247.9 21296.5 5837.8 6002.4 6013.0 6043.7 6053.7 5830.0 5995.6 6079.4 6090.6 6092.0 6185.5 6189.4 6194.9 6196.7 6204.1 5988.2 6072.4 6120.5 6120.8 6217.8 5877.0 5956.4 5966.5 5976.1 5977.3 14450.3 14647.8 14699.7 14700.9 15597.6 2353.7 2462.7 2550.9 2793.3 2913.3 10543.2 10690.6 10954.8 11082.0 11101.4 1969.8 2090.9 2115.3 2142.3 2158.7 63196.4 66402.3 75428.2 76105.8 88112.4 10795.0 11169.9 11322.4 11461.8 11490.2 14712.1 14783.9 14795.9 14986.7 15005.5 8489.6 8712.8 8764.1 8775.9 8877.6 8452.5 8482.0 8542.7 8698.6 8731.9 4794.4 4857.3 4901.2 4984.2 4997.0 1986.7 2041.4 2061.5 2144.6 2148.7 982.4 1015.2 1035.9 1038.7 1081.6 20809.7 20945.0 21335.7 21450.5 21606.1 1017.1 1017.9 1040.4 1044.1 1108.4 11474.7 11899.9 11995.6 12074.0 12107.9 448727.0 448990.0 450363.0 539604.0 551277.0 94871.5 97939.5 97945.0 99444.0 99481.5 1021.2 1049.7 1060.5 1067.1 1071.4 18131.5 18289.2 18501.8 18734.0 18841.9 44114.3 44459.8 44755.8 45529.5 45860.3 59911.1 60984.7 61484.4 61559.0 61928.7 7713.2 8255.9 8259.8 8696.6 8697.2 13207.1 14241.6 14482.3 14620.4 14843.1 12232.9 12540.9 12558.4 12825.1 12860.0 12216.9 12302.1 12351.6 12379.9 13115.5 12116.5 12333.6 12404.2 12437.5 12897.4 8308.0 8369.7 8423.0 8596.7 8756.7 12266.2 13015.8 13065.9 13089.1 13097.0 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | [#3] mootools.html Cycles:180 Average:6428.13 Median:4683.49 stddev:3725.79 (79.6%) stddev-sans-first:3717.07 03:57:43 INFO - PROCESS | 10106 | Values: 1398.1 1499.5 1570.0 1763.6 1906.8 8276.9 9371.4 9600.4 9612.9 9887.4 9684.4 9768.6 9773.3 9781.1 9784.5 2480.2 2514.4 2517.7 2518.9 2519.0 2431.9 2517.8 2520.9 2522.4 2524.4 2404.7 2428.4 2451.2 2452.2 2456.6 2410.5 2429.0 2437.0 2445.6 2449.9 2385.0 2410.6 2418.6 2419.3 2443.3 9641.1 9657.9 9719.6 9728.6 9851.1 5642.2 5818.6 5861.6 5890.1 5898.3 3806.8 3811.9 3867.9 3896.9 3921.9 1288.4 1290.7 1291.9 1315.3 1338.7 13361.1 13489.1 13504.5 13507.2 13515.1 4674.5 4864.7 4874.1 4967.5 4971.4 10940.5 10952.7 10954.6 10958.7 10968.6 5690.5 5700.7 5704.4 5705.0 5706.9 4747.3 4793.8 5225.8 5230.0 5343.0 4146.6 4151.9 4213.1 4215.0 4240.3 1217.6 1221.8 1223.8 1232.4 1234.6 989.7 1049.6 1088.9 1108.1 1109.4 6484.6 6632.1 6826.7 6911.9 6942.5 434.4 441.8 443.6 456.1 470.1 4635.8 4678.2 4688.8 4757.2 4781.2 12057.8 12343.4 12437.3 12476.9 12494.8 11539.6 11959.0 12023.2 12397.0 12418.7 9034.2 9262.9 9272.5 9291.5 9294.4 6716.6 7060.4 7060.4 7088.2 7121.9 11135.8 11631.8 11632.1 11638.5 11656.3 11333.2 11627.0 11799.9 11801.6 11867.8 8780.1 8795.7 8798.5 8877.8 8951.4 9578.6 9625.6 9636.3 9644.6 9653.3 8498.4 8622.6 8634.2 8660.4 8682.2 8161.1 8186.0 8453.5 8467.9 8515.1 7728.8 7794.4 7875.8 8083.5 8097.1 5837.5 5903.8 5963.5 5981.1 6034.8 5907.7 5949.9 5978.9 6055.0 6065.9 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | [#4] prototype.html Cycles:180 Average:4620.65 Median:5123.94 stddev:1312.24 (25.6%) stddev-sans-first:1276.23 03:57:43 INFO - PROCESS | 10106 | Values: 353.1 354.0 361.2 369.4 370.7 5499.5 5538.1 5538.5 5547.3 5552.5 5153.8 5234.9 5406.2 5423.4 5499.1 4324.8 4332.4 4334.9 4350.6 4361.7 4309.3 4331.2 4337.6 4346.8 4355.3 4118.9 4252.5 4283.4 4313.0 4318.0 4281.8 4302.2 4302.3 4303.3 4308.5 3802.7 3806.1 3807.5 3826.0 3827.4 5203.8 5293.7 5314.2 5330.7 5343.6 4904.8 4918.4 4918.5 4919.8 4929.7 5724.0 5731.0 5733.4 5739.2 5756.5 4058.7 4147.8 4173.4 4179.6 4188.9 5848.4 5913.2 5924.8 5926.8 5957.2 4237.5 4257.2 4258.4 4267.3 4289.4 4265.3 4287.8 4321.5 4361.9 4365.8 4865.5 4871.1 4873.2 4890.9 4898.5 5720.2 5771.6 5780.3 5802.7 5820.7 1410.2 1438.2 1490.2 1490.9 1495.4 974.4 988.8 992.7 996.7 1014.2 4900.2 4901.6 4926.2 4933.0 4980.5 5113.8 5133.4 5156.6 5160.7 5161.8 4696.5 4847.9 4871.2 4894.0 4900.2 2729.7 2730.4 2735.8 2738.4 2743.3 5246.4 5248.2 5277.4 5281.9 5302.6 4845.7 5175.0 5195.0 5202.6 5211.2 5226.2 5239.3 5272.7 5274.6 5284.9 5097.8 5099.9 5117.8 5159.0 5164.9 4799.7 5028.7 5040.9 5130.1 5132.8 5255.3 5263.7 5274.5 5322.6 5323.8 3889.8 3909.6 3967.6 3968.9 3971.9 4930.6 5074.6 5150.1 5153.7 5166.7 5556.5 5629.9 5762.2 5769.5 5776.3 5725.9 5727.6 5771.7 5780.2 5780.6 5446.3 5659.9 5692.4 5719.5 5720.6 5750.2 5760.6 5803.5 5805.9 5820.5 5659.5 5659.9 5661.0 5661.3 5663.0 03:57:43 INFO - PROCESS | 10106 | 03:57:43 INFO - PROCESS | 10106 | [#5] yui.html Cycles:180 Average:22278.23 Median:13216.70 stddev:32402.96 (245.2%) stddev-sans-first:32453.75 03:57:43 INFO - PROCESS | 10106 | Values: 806.8 826.8 867.3 887.5 924.2 16151.8 16381.8 16979.9 17004.2 17056.9 16180.4 16258.8 16277.0 16278.5 16290.4 749.8 889.9 891.2 895.7 913.4 898.3 899.8 900.1 900.2 900.6 957.4 962.6 963.3 963.4 967.1 783.7 796.9 918.3 931.9 957.8 695.2 696.5 697.1 697.3 698.8 15721.0 15758.8 15798.5 15837.9 15849.8 14415.3 14577.8 14766.9 14839.2 15058.1 13454.0 13516.5 13587.2 13916.2 14274.8 3888.7 4257.3 4263.3 4397.3 4478.2 72629.0 82222.6 82380.0 82618.6 82687.0 11047.7 11516.8 11814.1 12082.9 12179.1 22478.7 22515.2 22519.1 22523.3 22544.7 8397.9 8738.8 8855.2 8871.6 9306.2 4903.5 4982.6 5032.8 5122.7 5184.8 3985.1 4162.3 4165.2 4173.3 4337.8 752.5 752.8 753.9 773.2 787.6 625.3 652.5 733.6 755.3 786.5 10329.5 10797.6 10845.3 10905.7 10944.0 677.2 679.4 760.7 779.1 793.5 5097.6 5303.3 5305.1 5372.9 5390.9 126566.0 127089.0 128454.4 128784.0 128974.4 113285.4 113493.0 113811.0 114237.0 114480.4 70873.6 95819.0 97524.5 97786.5 102421.0 10189.1 10286.9 10411.8 10428.6 10470.6 55652.2 56151.7 56715.4 57196.3 57245.1 51740.5 52834.5 65108.0 65565.3 76081.0 13895.2 14192.9 14630.3 14678.9 14814.1 16188.8 16314.6 16601.3 17172.3 17850.1 14119.2 14296.8 14785.5 15581.5 16133.4 12880.4 14479.9 15611.8 15768.8 16163.5 14542.7 14603.6 14866.1 14959.9 15377.3 10805.1 11147.4 11331.6 11357.0 11462.9 12848.4 12962.2 12971.4 12990.2 13330.2 03:57:43 INFO - PROCESS | 10106 | -------- Summary: end -------- 03:57:43 INFO - PROCESS | 10106 | 03:57:44 INFO - TEST-INFO | 10106: exit 0 03:57:44 INFO - TEST-OK | dromaeo_css | took 1113006ms 03:57:44 INFO - TEST-START | kraken 03:57:44 INFO - operating with platform_type : linux_ 03:57:44 INFO - Initialising browser for kraken test... 03:57:44 INFO - TEST-INFO | started process 10308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe3Gbxh/profile http://localhost:38039/getInfo.html) 03:57:48 INFO - PROCESS | 10308 | __metrics Screen width/height:1600/1200 03:57:48 INFO - PROCESS | 10308 | colorDepth:24 03:57:48 INFO - PROCESS | 10308 | Browser inner width/height: 1024/697 03:57:48 INFO - PROCESS | 10308 | __metrics 03:57:49 INFO - PROCESS | 10308 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:57:49 INFO - PROCESS | 10308 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:57:50 INFO - TEST-INFO | 10308: exit 0 03:57:50 INFO - Browser initialized. 03:57:50 INFO - Running cycle 1/1 for kraken test... 03:57:50 INFO - TEST-INFO | started process 10396 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe3Gbxh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:01 INFO - PROCESS | 10396 | RSS: Main: 145100800 03:58:01 INFO - PROCESS | 10396 | 03:58:04 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38039/tests/kraken/driver.html?testName=audio-beat-detection) 03:58:05 INFO - PROCESS | 10396 | RSS: Main: 191467520 03:58:05 INFO - PROCESS | 10396 | 03:58:07 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38039/tests/kraken/driver.html?testName=audio-dft) 03:58:08 INFO - PROCESS | 10396 | RSS: Main: 181374976 03:58:08 INFO - PROCESS | 10396 | 03:58:11 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38039/tests/kraken/driver.html?testName=audio-fft) 03:58:11 INFO - PROCESS | 10396 | RSS: Main: 268963840 03:58:11 INFO - PROCESS | 10396 | 03:58:13 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38039/tests/kraken/driver.html?testName=audio-oscillator) 03:58:14 INFO - PROCESS | 10396 | RSS: Main: 175886336 03:58:14 INFO - PROCESS | 10396 | 03:58:16 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38039/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:58:17 INFO - PROCESS | 10396 | RSS: Main: 177364992 03:58:17 INFO - PROCESS | 10396 | 03:58:20 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38039/tests/kraken/driver.html?testName=imaging-darkroom) 03:58:20 INFO - PROCESS | 10396 | RSS: Main: 213000192 03:58:20 INFO - PROCESS | 10396 | 03:58:24 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38039/tests/kraken/driver.html?testName=imaging-desaturate) 03:58:24 INFO - PROCESS | 10396 | RSS: Main: 211476480 03:58:24 INFO - PROCESS | 10396 | 03:58:27 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38039/tests/kraken/driver.html?testName=json-parse-financial) 03:58:28 INFO - PROCESS | 10396 | RSS: Main: 211722240 03:58:28 INFO - PROCESS | 10396 | 03:58:29 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38039/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:58:30 INFO - PROCESS | 10396 | RSS: Main: 176295936 03:58:30 INFO - PROCESS | 10396 | 03:58:31 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:58:32 INFO - PROCESS | 10396 | RSS: Main: 180658176 03:58:32 INFO - PROCESS | 10396 | 03:58:34 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:58:35 INFO - PROCESS | 10396 | RSS: Main: 186494976 03:58:35 INFO - PROCESS | 10396 | 03:58:37 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:58:38 INFO - PROCESS | 10396 | RSS: Main: 181301248 03:58:38 INFO - PROCESS | 10396 | 03:58:40 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:58:41 INFO - PROCESS | 10396 | RSS: Main: 184410112 03:58:41 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | Cycle 1(1): loaded http://localhost:38039/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38039/tests/kraken/driver.html?testName=ai-astar) 03:58:43 INFO - PROCESS | 10396 | RSS: Main: 234860544 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | __start_tp_report 03:58:43 INFO - PROCESS | 10396 | _x_x_mozilla_page_load 03:58:43 INFO - PROCESS | 10396 | _x_x_mozilla_page_load_details 03:58:43 INFO - PROCESS | 10396 | |i|pagename|runs| 03:58:43 INFO - PROCESS | 10396 | |0;ai-astar;101;110;110;110;110;109;109;110;108;109 03:58:43 INFO - PROCESS | 10396 | |1;audio-beat-detection;151;114;118;179;119;116;150;118;143;122 03:58:43 INFO - PROCESS | 10396 | |2;audio-dft;161;160;160;160;160;162;159;160;160;161 03:58:43 INFO - PROCESS | 10396 | |3;audio-fft;74;74;74;112;75;75;75;75;102;75 03:58:43 INFO - PROCESS | 10396 | |4;audio-oscillator;83;84;128;83;83;82;126;83;84;120 03:58:43 INFO - PROCESS | 10396 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;116;117;116 03:58:43 INFO - PROCESS | 10396 | |6;imaging-darkroom;145;146;146;145;145;146;146;145;145;146 03:58:43 INFO - PROCESS | 10396 | |7;imaging-desaturate;94;94;95;95;95;95;95;95;95;94 03:58:43 INFO - PROCESS | 10396 | |8;json-parse-financial;50;51;52;51;50;51;51;50;52;50 03:58:43 INFO - PROCESS | 10396 | |9;json-stringify-tinderbox;59;61;60;60;60;60;60;60;60;61 03:58:43 INFO - PROCESS | 10396 | |10;stanford-crypto-aes;73;71;73;72;72;77;71;71;74;71 03:58:43 INFO - PROCESS | 10396 | |11;stanford-crypto-ccm;124;117;124;123;132;122;115;116;156;122 03:58:43 INFO - PROCESS | 10396 | |12;stanford-crypto-pbkdf2;156;156;155;152;154;152;157;156;150;154 03:58:43 INFO - PROCESS | 10396 | |13;stanford-crypto-sha256-iterative;61;61;62;62;62;62;61;64;62;61 03:58:43 INFO - PROCESS | 10396 | __end_tp_report 03:58:43 INFO - PROCESS | 10396 | __start_cc_report 03:58:43 INFO - PROCESS | 10396 | _x_x_mozilla_cycle_collect,1627 03:58:43 INFO - PROCESS | 10396 | __end_cc_report 03:58:43 INFO - PROCESS | 10396 | __startTimestamp1472122723253__endTimestamp 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | ------- Summary: start ------- 03:58:43 INFO - PROCESS | 10396 | Number of tests: 14 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:2.76 (2.5%) stddev-sans-first:0.73 03:58:43 INFO - PROCESS | 10396 | Values: 101.0 110.0 110.0 110.0 110.0 109.0 109.0 110.0 108.0 109.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:132.50 stddev:21.72 (16.4%) stddev-sans-first:22.04 03:58:43 INFO - PROCESS | 10396 | Values: 151.0 114.0 118.0 179.0 119.0 116.0 150.0 118.0 143.0 122.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 03:58:43 INFO - PROCESS | 10396 | Values: 161.0 160.0 160.0 160.0 160.0 162.0 159.0 160.0 160.0 161.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.86 (18.5%) stddev-sans-first:14.46 03:58:43 INFO - PROCESS | 10396 | Values: 74.0 74.0 74.0 112.0 75.0 75.0 75.0 75.0 102.0 75.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:83.00 stddev:20.16 (24.3%) stddev-sans-first:20.86 03:58:43 INFO - PROCESS | 10396 | Values: 83.0 84.0 128.0 83.0 83.0 82.0 126.0 83.0 84.0 120.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:58:43 INFO - PROCESS | 10396 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 116.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 03:58:43 INFO - PROCESS | 10396 | Values: 145.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 03:58:43 INFO - PROCESS | 10396 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.79 (1.5%) stddev-sans-first:0.78 03:58:43 INFO - PROCESS | 10396 | Values: 50.0 51.0 52.0 51.0 50.0 51.0 51.0 50.0 52.0 50.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 03:58:43 INFO - PROCESS | 10396 | Values: 59.0 61.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#10] stanford-crypto-aes Cycles:10 Average:72.50 Median:72.50 stddev:1.90 (2.6%) stddev-sans-first:2.01 03:58:43 INFO - PROCESS | 10396 | Values: 73.0 71.0 73.0 72.0 72.0 77.0 71.0 71.0 74.0 71.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:123.50 stddev:11.92 (9.7%) stddev-sans-first:12.64 03:58:43 INFO - PROCESS | 10396 | Values: 124.0 117.0 124.0 123.0 132.0 122.0 115.0 116.0 156.0 122.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.20 Median:155.50 stddev:2.25 (1.4%) stddev-sans-first:2.29 03:58:43 INFO - PROCESS | 10396 | Values: 156.0 156.0 155.0 152.0 154.0 152.0 157.0 156.0 150.0 154.0 03:58:43 INFO - PROCESS | 10396 | 03:58:43 INFO - PROCESS | 10396 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 03:58:43 INFO - PROCESS | 10396 | Values: 61.0 61.0 62.0 62.0 62.0 62.0 61.0 64.0 62.0 61.0 03:58:43 INFO - PROCESS | 10396 | -------- Summary: end -------- 03:58:43 INFO - PROCESS | 10396 | 03:58:44 INFO - TEST-INFO | 10396: exit 0 03:58:44 INFO - TEST-OK | kraken | took 60004ms 03:58:44 INFO - SUITE-END | took 1173s 03:58:44 INFO - Completed test suite (00:19:33) 03:58:44 INFO - javascript benchmark 03:58:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3244.756643251756, 3254.576905002349, 3264.8530816113266, 3281.34373125375, 3289.0461766087838, 5641.802536911209, 5715.685637289947, 5721.9999999999945, 5726.0, 5752.626079304842, 5721.856953576163, 5775.797847075353, 5794.956907756613, 5796.797112101077, 5796.884062318751, 5083.288339632454, 5215.58275337973, 5219.478052194788, 5265.65773224741, 5288.65623734456, 5121.155009423441, 5233.084210263208, 5282.2076688496645, 5284.762185701634, 5296.549793267577, 5000.074986127578, 5042.672226305297, 5054.064997975368, 5097.49025097491, 5098.694078355292, 5161.5870730341485, 5172.12073947428, 5185.662931909414, 5192.71440070796, 5208.791648334062, 5020.59835213182, 5067.113255180366, 5091.363579552556, 5120.052790233819, 5123.923141152885, 5567.832965011056, 5593.3847276799515, 5610.887782244332, 5623.831285061455, 5661.716914154275, 6118.8776224475705, 6128.356522565111, 6136.8158955230965, 6144.078388241799, 6145.446909778141, 6069.848253793646, 6076.7903407876165, 6103.176071230416, 6125.632462052291, 6182.474489668383, 5769.105788602773, 5783.306003279633, 5802.100674395476, 5811.476967072899, 5813.5349172066135, 6034.215551978303, 6313.842153946096, 6327.588706734047, 6332.525060620472, 6334.429901308904, 5935.0, 5977.760889564369, 6008.399160084044, 6091.604045737102, 6094.786682466092, 6122.326544080148, 6149.046897730859, 6154.323024467305, 6154.78458253959, 6157.199564056644, 5903.35063143054, 6132.264128304632, 6139.10982907476, 6142.539309551801, 6150.876982460415, 5987.670678112746, 5992.011318132547, 6035.577509574287, 6074.635521868702, 6134.110553969649, 4641.187792136363, 4676.298555216745, 4676.438827340741, 4681.29780532916, 4706.529347065266, 4114.033202197481, 4165.645920096765, 4177.6866734995, 4187.581241875788, 4190.329547272483, 5692.829215123553, 5735.77056917727, 5740.0815869460685, 5794.507466865363, 5830.47525722687, 6099.786507472217, 6106.596914875623, 6107.7206961209795, 6112.786052488142, 6144.5084393248435, 5627.549796016309, 5766.4233576642, 5776.191333213354, 5779.739911703983, 5790.623609465371, 5189.714565698923, 5225.059489291888, 5228.189630607262, 5283.445238250005, 5300.761465734051, 6227.875442491216, 6259.593126446766, 6271.62370257786, 6271.749130034838, 6273.560850740404, 5917.970410147922, 6076.088586712029, 6080.7567697291615, 6103.206583144163, 6106.389361063858, 6334.651594162366, 6387.233531976192, 6402.327755585597, 6406.519511036784, 6411.807645770635, 6026.246719160105, 6068.0, 6069.0592958091565, 6075.696215189135, 6080.574359794772, 5869.207656966255, 6196.504279657706, 6197.876042479215, 6210.875782484415, 6214.9689251553455, 5913.142594323885, 5970.0746384310505, 5996.8800623988145, 6071.605345652519, 6104.084387341757, 5972.850678733066, 6011.549133814894, 6018.51851851842, 6023.698815059317, 6027.336992930862, 5712.714364281686, 5931.1992880960515, 5936.554758393224, 5940.138679891478, 5940.198073260054, 5959.582829201914, 6002.639841609517, 6020.819375418744, 6081.269411483649, 6095.0752189950945, 5909.70451477433, 5916.940830591639, 5918.17145599625, 5919.733611987384, 5946.940530594638], "value": 5629.031471986804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [620.8944479438497, 644.7163248170805, 664.7540410048281, 678.1997243252963, 687.2508965227901, 35188.12029699256, 47673.52326476734, 48814.0, 48841.0, 48872.999999999956, 49831.50168498323, 50267.497325026736, 50310.496895031036, 50325.74837125818, 50361.0, 8490.78773030673, 9240.630374785018, 9384.577694003787, 9386.0, 9394.483303418308, 11097.33415995039, 11368.886311136906, 11370.431478426088, 11751.471183796706, 11751.588694395698, 11472.942635286854, 11578.24741391811, 11585.36280504572, 11587.826182607268, 11591.362475063868, 11392.943035284812, 11434.656960291204, 11453.656390308304, 11460.770784584345, 11467.426628668616, 11165.27425717334, 11210.66368008961, 11211.271267367594, 11289.435528223556, 11291.887081129247, 47483.0, 47718.761406193094, 47767.0, 47853.76073119647, 47908.28137577904, 3762.661360477543, 3785.4511095890944, 3825.6365645263654, 3836.7058143532795, 3914.823832927525, 36123.65196262261, 36128.458073128924, 36207.81896090529, 36504.0, 38572.22855542874, 4715.31627913954, 4834.879128021792, 4838.491958344358, 4862.6839255448285, 4931.926021109686, 376848.0, 378800.0, 379346.0, 379861.99999999726, 380323.0, 66839.66580167068, 67102.66448667725, 67279.3272067273, 67285.96284222962, 67332.0, 40868.0, 41636.58363416388, 41734.582654173675, 43975.560244397784, 44084.0, 18607.06964651762, 18920.905395472935, 18935.526611834815, 18948.0, 18954.62090758177, 12525.624231272894, 13765.862341376658, 13877.0, 14145.646358840906, 14241.0, 22013.559728805336, 22081.0, 22242.888785556293, 22270.220542280942, 22338.66492002621, 7656.464047516732, 7800.531968081819, 7885.842283154419, 7910.248526389983, 8038.356931445471, 947.6872632031414, 1009.2733232072895, 1017.3488967061087, 1044.5612842606124, 1064.5156453813497, 36997.26005479929, 36998.260034799154, 37287.25425491475, 37381.252374952346, 37480.43779343312, 1151.5911851292838, 1175.2360965372577, 1181.6986668399504, 1193.9164258501996, 1194.8028575285155, 18939.621207575772, 19350.90324548368, 19424.805751942582, 19521.414357569294, 19693.70459443081, 367260.0, 373414.0, 375639.0, 519650.0, 655425.0, 67332.32667673359, 68158.65920670365, 68197.65901170562, 68229.0, 68410.31589684039, 1342.8858547023417, 1440.3878351700484, 1446.1756798624892, 1476.9926150369395, 1495.461633811827, 33767.662323376455, 38161.98677149632, 38366.232675346895, 38506.422403664525, 38624.613753862104, 58422.12366814416, 59255.70372148111, 59265.40734592599, 59337.703311483165, 59346.10980835377, 400782.0, 404905.0, 405765.0, 406086.0, 406689.0, 28868.711312886604, 31838.681613184497, 32643.18392040123, 32917.341653167285, 33232.833835830665, 55383.446165537825, 55506.0, 55641.72179139078, 55642.72178639243, 55654.0, 45430.318545221184, 46347.0, 47168.764156179, 47218.76390618025, 48523.75738121287, 45952.62258966779, 48050.0, 48226.27660585024, 48264.0, 49150.0, 46098.53901460942, 46344.536554634025, 46653.300200497695, 47353.52646473629, 47518.287225690954, 39779.40330895097, 41565.584344156174, 41602.791986041084, 41609.16781664289, 42487.0, 30529.332015673124, 30687.846560767946, 30708.692913070583, 31078.533821992234, 31312.21719457032], "value": 22239.987219111514, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.79462077030314, 409.3266576481686, 420.77488543629164, 429.2081110351402, 441.89173652455156, 15612.843871561281, 20814.687779683318, 20886.164553417846, 21173.258935937247, 22132.557348853014, 20757.688634670492, 21035.619690840427, 21061.0, 21247.893760531213, 21296.4675883103, 5837.795677151301, 6002.369751176129, 6013.037913933771, 6043.6675982820925, 6053.667048312341, 5829.950608890398, 5995.580309378345, 6079.361667024965, 6090.5736598438125, 6092.025275955848, 6185.505159587245, 6189.38106189382, 6194.907076393858, 6196.659183744892, 6204.069389591575, 5988.2215312009375, 6072.362401947776, 6120.510359171256, 6120.816375508742, 6217.844553886144, 5877.031194549788, 5956.434138756811, 5966.522678185735, 5976.103584462366, 5977.252843394575, 14450.349734261983, 14647.780283295759, 14699.706005879929, 14700.926495367456, 15597.610059748484, 2353.658719485682, 2462.667539882129, 2550.948981020369, 2793.3435642623967, 2913.257119434552, 10543.209259305582, 10690.572377104907, 10954.780904381867, 11082.0, 11101.3894235817, 1969.8030196980187, 2090.937271881846, 2115.280804526453, 2142.271627646592, 2158.6761985702115, 63196.36803631951, 66402.3359766401, 75428.24571754267, 76105.81418022007, 88112.35662930059, 10794.974477424632, 11169.888301116885, 11322.37726925027, 11461.770764584662, 11490.195686301879, 14712.117272963442, 14783.852161478248, 14795.852041479662, 14986.70026599462, 15005.549833505012, 8489.575521224038, 8712.78218044554, 8764.079771624016, 8775.868361974575, 8877.556122193864, 8452.450590711584, 8481.982162140583, 8542.658293668308, 8698.565071746387, 8731.91268087311, 4794.424669039738, 4857.271409288635, 4901.1913034349645, 4984.152694042022, 4996.975015124901, 1986.7317912081974, 2041.428400047989, 2061.4743240473586, 2144.624690679125, 2148.656215005623, 982.4007355513274, 1015.1574193419444, 1035.8549803027581, 1038.6987773545586, 1081.6322450366913, 20809.687854682194, 20944.952752362322, 21335.67996480054, 21450.463738406666, 21606.135754569954, 1017.1100287248657, 1017.9032991865761, 1040.4069680282166, 1044.1438020823005, 1108.3793075877534, 11474.655760327203, 11899.940500297444, 11995.580154694544, 12074.0, 12107.939460302641, 448727.0, 448990.0, 450363.0, 539604.0, 551277.0, 94871.52564237134, 97939.51030244946, 97945.0, 99444.0, 99481.50259248658, 1021.2187676427538, 1049.7270709615402, 1060.5333653192724, 1067.146282973618, 1071.4000159910474, 18131.54671133232, 18289.17698703535, 18501.79738317005, 18734.0, 18841.90579047151, 44114.33828492511, 44459.777701111285, 44755.776221118685, 45529.54470455253, 45860.31209531793, 59911.101333479164, 60984.69507652433, 61484.385156149656, 61559.0, 61928.69035654793, 7713.2286771322415, 8255.917440825515, 8259.752207433787, 8696.565171741515, 8697.173768491983, 13207.141535800141, 14241.64395890111, 14482.34829432657, 14620.41518339297, 14843.109413435228, 12232.938835305766, 12540.937295313464, 12558.372081396077, 12825.102242842911, 12860.0, 12216.877831221575, 12302.138850280395, 12351.629451116482, 12379.93810030944, 13115.540956066492, 12116.515339386326, 12333.62999110028, 12404.19372740769, 12437.502499900265, 12897.355132243538, 8307.958460207661, 8369.707060252862, 8423.0, 8596.699115530926, 8756.693515726918, 12266.202696824677, 13015.804762928374, 13065.934670326587, 13089.08376413642, 13097.0], "value": 9581.560049487724, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.1052126638947, 1499.467688970415, 1570.0030480644791, 1763.585557394012, 1906.761654793151, 8276.875846862295, 9371.437713737172, 9600.375975561583, 9612.855807162898, 9887.35732177408, 9684.37051591646, 9768.560414781334, 9773.31586788925, 9781.07079827417, 9784.461854598012, 2480.2187310997024, 2514.358838496181, 2517.697876254852, 2518.8866501007496, 2519.017583142576, 2431.878406079698, 2517.848929064253, 2520.9117680881172, 2522.382016405974, 2524.3941454050987, 2404.723456802473, 2428.429319110008, 2451.166603354859, 2452.19077704358, 2456.5578195924727, 2410.505846301513, 2428.9757102428925, 2437.049550675229, 2445.6331550267423, 2449.865257410842, 2384.9761502384927, 2410.590199666061, 2418.625113107471, 2419.2863105383776, 2443.2914454808083, 9641.132298093205, 9657.903420965771, 9719.56261968213, 9728.562214700285, 9851.113399794052, 5642.153676948449, 5818.5926985111055, 5861.618994765369, 5890.057590785496, 5898.292204935392, 3806.809659517013, 3811.9237615247816, 3867.941980870289, 3896.902577435558, 3921.9215615687813, 1288.4073326269927, 1290.6644272489127, 1291.8901893339078, 1315.3160356614508, 1338.738945905549, 13361.13152645075, 13489.123206991515, 13504.459821607124, 13507.189568625914, 13515.121517101357, 4674.485806561275, 4864.70811751289, 4874.147024270738, 4967.453580106186, 4971.403431588233, 10940.4529773511, 10952.671419857417, 10954.616588419527, 10958.671239862817, 10968.561257549609, 5690.487856092972, 5700.743466544015, 5704.401037890962, 5705.030144875381, 5706.857328566736, 4747.335373047776, 4793.784279707422, 5225.817096401608, 5230.006298803215, 5342.973285133549, 4146.574780860194, 4151.875443736692, 4213.094184750232, 4215.0, 4240.257954857949, 1217.5677634439824, 1221.7922953097816, 1223.804191329383, 1232.445399570179, 1234.6481252842896, 989.7327721515151, 1049.559185142242, 1088.9128869690405, 1108.0747575774158, 1109.4064675398622, 6484.643344615997, 6632.1046658701425, 6826.726930922807, 6911.89632155518, 6942.479314051467, 434.4222184494678, 441.77248716910816, 443.6140557714809, 456.07416943604534, 470.097412967104, 4635.768211589474, 4678.169871986937, 4688.812447502061, 4757.16749568831, 4781.2350023996, 12057.819132713017, 12343.382830858422, 12437.315947622787, 12476.875231247754, 12494.81257781134, 11539.596114136133, 11958.983486403578, 12023.21849079807, 12397.0, 12418.689532761573, 9034.186923176945, 9262.861057084148, 9272.536373181314, 9291.488968106683, 9294.395864268801, 6716.630585317855, 7060.399866011447, 7060.43516518667, 7088.2202957675645, 7121.893171602534, 11135.832962505407, 11631.76736465249, 11632.127590430582, 11638.476268567765, 11656.30062196271, 11333.150013748836, 11627.0, 11799.941000294944, 11801.645950621494, 11867.821982670093, 8780.121987801169, 8795.736127916172, 8798.516081615573, 8877.822443551222, 8951.41815781998, 9578.616855325708, 9625.614975400906, 9636.325457217927, 9644.614215431304, 9653.324267301221, 8498.362622803437, 8622.568871556523, 8634.179752923463, 8660.35047371437, 8682.218600326001, 8161.102278749214, 8185.959070204611, 8453.535055572003, 8467.872981905399, 8515.105913878953, 7728.8454230916195, 7794.376449884109, 7875.842483150191, 8083.514989100673, 8097.1497992712275, 5837.474627283565, 5903.763849446146, 5963.522918166623, 5981.0729336952845, 6034.849128771816, 5907.734151963258, 5949.851253718692, 5978.923793716998, 6055.031195008777, 6065.939340606537], "value": 4995.279116614788, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.08903030182125, 353.9946900796487, 361.21976530693706, 369.4255432801992, 370.71454979665674, 5499.450054994503, 5538.141588053853, 5538.501534861862, 5547.306586676666, 5552.528035117015, 5153.814622636786, 5234.895302093965, 5406.189071639256, 5423.376311724153, 5499.092649712803, 4324.783760811963, 4332.4151239582725, 4334.851264414932, 4350.564943505656, 4361.6510679145595, 4309.31051031835, 4331.155424692186, 4337.587929146726, 4346.8043938022865, 4355.344968911809, 4118.938215926763, 4252.532221455637, 4283.400323954649, 4312.994457577226, 4318.0, 4281.764502952336, 4302.161078589661, 4302.333138363543, 4303.289957157065, 4308.482982042144, 3802.714796390254, 3806.1436176859984, 3807.485989391452, 3826.0, 3827.445020472044, 5203.843884683428, 5293.655912365684, 5314.202869569557, 5330.7334633268565, 5343.572514198855, 4904.773806548363, 4918.409790825088, 4918.483559226265, 4919.843836698373, 4929.679570827885, 5724.0, 5731.0, 5733.369329373765, 5739.196512488255, 5756.453136951983, 4058.6955978301744, 4147.838605190552, 4173.436586060873, 4179.6029377209115, 4188.853390131361, 5848.41515848416, 5913.201717768089, 5924.822255332347, 5926.7925622603, 5957.225560677128, 4237.533871274158, 4257.169851878916, 4258.361245813091, 4267.274563324242, 4289.399484072233, 4265.253580623378, 4287.828486860553, 4321.524632290446, 4361.890952726208, 4365.759883206454, 4865.489123642037, 4871.123197824355, 4873.220284754422, 4890.902181956341, 4898.510148985144, 5720.170575266563, 5771.567132465082, 5780.277465316835, 5802.65184088956, 5820.650760954271, 1410.2173293822027, 1438.201798002098, 1490.217635741223, 1490.903091299062, 1495.364470100203, 974.4104816586005, 988.7583533033358, 992.6674564021134, 996.7159359582485, 1014.2089170447063, 4900.162439085343, 4901.583365413909, 4926.236433352836, 4932.975335123373, 4980.477049909708, 5113.846584602468, 5133.358330208724, 5156.613254005964, 5160.716160611202, 5161.845144645667, 4696.459907110658, 4847.909220425376, 4871.244957031666, 4893.972265824145, 4900.215965445512, 2729.6587926509187, 2730.3720144366907, 2735.8358498490156, 2738.438620082878, 2743.27303264635, 5246.370435547682, 5248.212768084742, 5277.419483856773, 5281.894362112736, 5302.553340328817, 4845.6608037437045, 5174.97412512935, 5194.974025129925, 5202.60980426462, 5211.1922651989, 5226.216067589892, 5239.253933437534, 5272.736363181825, 5274.5780337572905, 5284.867878303073, 5097.847064588068, 5099.923501147411, 5117.795288188579, 5159.0, 5164.870878228075, 4799.712017278975, 5028.673136246132, 5040.873978150429, 5130.076221416969, 5132.769025393941, 5255.343082114736, 5263.710495922761, 5274.525292723673, 5322.627416080837, 5323.840284791462, 3889.8136068498247, 3909.569947305737, 3967.6311672472953, 3968.900777480586, 3971.92056158881, 4930.6055515557955, 5074.594032477319, 5150.124478838531, 5153.7165455900285, 5166.689998600096, 5556.527695145958, 5629.915551266652, 5762.1644861494015, 5769.480746732815, 5776.335721392094, 5725.8568535786935, 5727.570432217683, 5771.7114144293455, 5780.190773291826, 5780.566457515619, 5446.25007422649, 5659.85850353728, 5692.373838877637, 5719.48524632785, 5720.599558031065, 5750.239884855209, 5760.567957403296, 5803.47768700819, 5805.867855768085, 5820.505257053029, 5659.462351076641, 5659.886802263849, 5660.971695141498, 5661.320641522879, 5663.046249044073], "value": 4214.60632895603, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.7579726082174, 826.772637524681, 867.262826597392, 887.5429153985698, 924.2421214604025, 16151.838481615181, 16381.83618163818, 16979.91510042451, 17004.234809433576, 17056.91471542642, 16180.433684821033, 16258.75611865823, 16277.0, 16278.51164465068, 16290.42983495578, 749.8200431896361, 889.9243564297043, 891.1756625121752, 895.6596493332524, 913.3743385780729, 898.2634239923266, 899.7705585075796, 900.0774206438421, 900.2482926756167, 900.621738869673, 957.4016239850093, 962.6101428921298, 963.3401120232628, 963.3641796414367, 967.1344146988441, 783.6865253898458, 796.8525822722814, 918.3296193778513, 931.8835145606799, 957.7749228931195, 695.1762161838187, 696.5159214346031, 697.1146643762394, 697.3340459860821, 698.7868700046483, 15721.0, 15758.76361854573, 15798.526044218692, 15837.92081039599, 15849.762253566205, 14415.279236038261, 14577.781333280009, 14766.926165369212, 14839.179827100766, 15058.096514209074, 13454.0, 13516.526921557797, 13587.184768913798, 13916.234607096605, 14274.785878211835, 3888.747231429948, 4257.276263035292, 4263.253930562139, 4397.274449715793, 4478.171538265398, 72629.0, 82222.58888705578, 82380.0, 82618.58690706508, 82687.0, 11047.72380690489, 11516.827247591293, 11814.113941454254, 12082.939585302192, 12179.147459677915, 22478.66282005771, 22515.211967581385, 22519.099236030375, 22523.32430027102, 22544.66183007256, 8397.874031889527, 8738.781530461662, 8855.203031727175, 8871.600777964877, 9306.2089722373, 4903.53416425439, 4982.60139188864, 5032.79868805251, 5122.667026643257, 5184.792608295701, 3985.0834308109297, 4162.292410290197, 4165.166966606691, 4173.290540608104, 4337.82648694055, 752.4657493179903, 752.7553545097865, 753.8982237397881, 773.1958762886647, 787.5589669784939, 625.2871726232099, 652.4812773844804, 733.5965219129457, 755.3353049316567, 786.4966421490251, 10329.483525823829, 10797.568097276178, 10845.349279043125, 10905.727356815984, 10944.0, 677.2381071294793, 679.3885503047297, 760.7223363472293, 779.080684791951, 793.476305638276, 5097.64316497849, 5303.28405665238, 5305.082045563207, 5372.91940620891, 5390.865228369322, 126566.0, 127089.0, 128454.35772821076, 128784.0, 128974.35512822376, 113285.43357283161, 113493.0, 113811.0, 114237.0, 114480.42759786148, 70873.64563177254, 95819.0, 97524.51237743766, 97786.51106744421, 102421.0, 10189.082982531607, 10286.897131028594, 10411.84382234252, 10428.634997775041, 10470.633527826794, 55652.16521752096, 56151.71924140353, 56715.432845672665, 57196.28411147672, 57245.14132288102, 51740.48259517357, 52834.47165528449, 65108.0, 65565.34434655592, 76081.0, 13895.235762033186, 14192.858071419567, 14630.268486575842, 14678.926605366905, 14814.111153330834, 16188.838111618734, 16314.592135196715, 16601.335946562485, 17172.31310747606, 17850.107494624957, 14119.152850828983, 14296.784773294386, 14785.482508112165, 15581.532554023397, 16133.354665813236, 12880.42038108306, 14479.92760036193, 15611.765823512429, 15768.842311576738, 16163.515094547183, 14542.709145816812, 14603.634909126931, 14866.108033518023, 14959.925200374364, 15377.308021138851, 10805.135589153006, 11147.442627868737, 11331.603393881174, 11357.0, 11462.885371146182, 12848.42182101789, 12962.235341512987, 12971.41628626733, 12990.155639883378, 13330.200187988752], "value": 8197.629403463876, "unit": "score"}], "name": "dromaeo_css", "value": 7691.4687043774675, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 110.0, 108.0, 109.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 118.0, 179.0, 119.0, 116.0, 150.0, 118.0, 143.0, 122.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 162.0, 159.0, 160.0, 160.0, 161.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 112.0, 75.0, 75.0, 75.0, 75.0, 102.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 128.0, 83.0, 83.0, 82.0, 126.0, 83.0, 84.0, 120.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 52.0, 51.0, 50.0, 51.0, 51.0, 50.0, 52.0, 50.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 73.0, 72.0, 72.0, 77.0, 71.0, 71.0, 74.0, 71.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 117.0, 124.0, 123.0, 132.0, 122.0, 115.0, 116.0, 156.0, 122.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 156.0, 155.0, 152.0, 154.0, 152.0, 157.0, 156.0, 150.0, 154.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 64.0, 62.0, 61.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1459.8999999999999, "alertThreshold": 2.0}]} 03:58:44 INFO - Return code: 0 03:58:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:58:44 INFO - # TBPL SUCCESS # 03:58:44 INFO - Running post-action listener: _resource_record_post_action 03:58:44 INFO - [mozharness: 2016-08-25 10:58:44.568387Z] Finished run-tests step (success) 03:58:44 INFO - Running post-run listener: _resource_record_post_run 03:58:44 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 328114176; Read time: 0; Write time: 346536 03:58:44 INFO - TinderboxPrint: CPU usage
13.0% 03:58:44 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:58:44 INFO - TinderboxPrint: I/O write bytes / time
328,114,176 / 346,536 03:58:44 INFO - TinderboxPrint: CPU idle
8,260.9 (87.0%) 03:58:44 INFO - TinderboxPrint: CPU user
1,189.0 (12.5%) 03:58:44 INFO - TinderboxPrint: Swap in / out
0 / 0 03:58:44 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78512128; Read time: 0; Write time: 68432 03:58:44 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248619008; Read time: 0; Write time: 278100 03:58:45 INFO - Running post-run listener: _upload_blobber_files 03:58:45 INFO - Blob upload gear active. 03:58:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:58:45 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 03:58:45 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'] 03:58:45 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 03:58:45 INFO - (blobuploader) - INFO - Open directory for files ... 03:58:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:58:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:58:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:46 INFO - (blobuploader) - INFO - Done attempting. 03:58:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:58:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:58:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:47 INFO - (blobuploader) - INFO - Done attempting. 03:58:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:58:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:58:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:50 INFO - (blobuploader) - INFO - Done attempting. 03:58:50 INFO - (blobuploader) - INFO - Iteration through files over. 03:58:50 INFO - Return code: 0 03:58:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:58:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:58:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a89aed6aa6f5e60b23da4820d854949722a4fb8bb3f59c8ef31eb12ac571c5ffc69ae485ed2b1a35936d2612700f5ef75247973648ee71428b7c6d0b75af83fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3e2e672c2066bb58c9207038e85e279104071673ce5587f3ea408f4d895ba4022a5177534831d0afb990b2a2572c69fbc0089ceb65a27432f3fb8b3588a4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41c3f48b2c5274d5fbd03368e55d85a82f0404bf5eb3c9dd634e19dc97173e8a687b1955e332935426bfaa2b2f3032d3fe13ce16a9addf1005f437f9fcc46e68"} 03:58:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:58:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:58:50 INFO - Contents: 03:58:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a89aed6aa6f5e60b23da4820d854949722a4fb8bb3f59c8ef31eb12ac571c5ffc69ae485ed2b1a35936d2612700f5ef75247973648ee71428b7c6d0b75af83fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3e2e672c2066bb58c9207038e85e279104071673ce5587f3ea408f4d895ba4022a5177534831d0afb990b2a2572c69fbc0089ceb65a27432f3fb8b3588a4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41c3f48b2c5274d5fbd03368e55d85a82f0404bf5eb3c9dd634e19dc97173e8a687b1955e332935426bfaa2b2f3032d3fe13ce16a9addf1005f437f9fcc46e68"} 03:58:50 INFO - Running post-run listener: copy_logs_to_upload_dir 03:58:50 INFO - Copying logs to upload dir... 03:58:50 INFO - mkdir: /builds/slave/test/build/upload/logs 03:58:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.104143 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-08-25 03:58:49.699923) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 03:58:49.703412) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a89aed6aa6f5e60b23da4820d854949722a4fb8bb3f59c8ef31eb12ac571c5ffc69ae485ed2b1a35936d2612700f5ef75247973648ee71428b7c6d0b75af83fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3e2e672c2066bb58c9207038e85e279104071673ce5587f3ea408f4d895ba4022a5177534831d0afb990b2a2572c69fbc0089ceb65a27432f3fb8b3588a4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41c3f48b2c5274d5fbd03368e55d85a82f0404bf5eb3c9dd634e19dc97173e8a687b1955e332935426bfaa2b2f3032d3fe13ce16a9addf1005f437f9fcc46e68"} build_url:https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/ZzU0m7PmTSiLdl03MuP2_A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a89aed6aa6f5e60b23da4820d854949722a4fb8bb3f59c8ef31eb12ac571c5ffc69ae485ed2b1a35936d2612700f5ef75247973648ee71428b7c6d0b75af83fc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3e2e672c2066bb58c9207038e85e279104071673ce5587f3ea408f4d895ba4022a5177534831d0afb990b2a2572c69fbc0089ceb65a27432f3fb8b3588a4e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/41c3f48b2c5274d5fbd03368e55d85a82f0404bf5eb3c9dd634e19dc97173e8a687b1955e332935426bfaa2b2f3032d3fe13ce16a9addf1005f437f9fcc46e68"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 03:58:49.733598) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:58:49.734054) ========= 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=6d15e502d8e29d5d2676d664000001c3-1472120857.162535-646536251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 03:58:49.818577) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 03:58:49.819412) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 03:58:49.820090) ========= ========= Total master_lag: 0.16 =========