builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1473682356.7 results: success (0) buildid: 20160910055116 builduid: fb98bf9a800847589b1767e37e53bef3 revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.696613) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.699878) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:36.700268) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.139174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.139502) ========= 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=af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 05:12:38-- 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% 227M=0s 2016-09-12 05:12:38 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153344 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.340092) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.340375) ========= 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=af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016708 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:37.404661) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 05:12:37.405064) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8663d41e015f3c9933a08a65c8349b86f2263354 --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 8663d41e015f3c9933a08a65c8349b86f2263354 --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=af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 05:12:38,388 truncating revision to first 12 chars 2016-09-12 05:12:38,388 Setting DEBUG logging. 2016-09-12 05:12:38,388 attempt 1/10 2016-09-12 05:12:38,388 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8663d41e015f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 05:12:39,632 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.620337 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 05:12:39.039035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:39.039347) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:39.039761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:39.040050) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:39.050554) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-12 05:12:39.050962) ========= /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=af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:12:40 INFO - MultiFileLogger online at 20160912 05:12:40 in /builds/slave/test 05:12:40 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 05:12:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:40 INFO - {'append_to_log': False, 05:12:40 INFO - 'base_work_dir': '/builds/slave/test', 05:12:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:12:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:40 INFO - 'branch': 'Mozilla-Aurora', 05:12:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:40 INFO - 'config_files': ('talos/linux_config.py',), 05:12:40 INFO - 'default_actions': ('clobber', 05:12:40 INFO - 'read-buildbot-config', 05:12:40 INFO - 'download-and-extract', 05:12:40 INFO - 'populate-webroot', 05:12:40 INFO - 'create-virtualenv', 05:12:40 INFO - 'install', 05:12:40 INFO - 'run-tests'), 05:12:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:40 INFO - 'download_minidump_stackwalk': True, 05:12:40 INFO - 'download_symbols': 'ondemand', 05:12:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:40 INFO - 'installer_path': 'installer.exe', 05:12:40 INFO - 'log_level': 'info', 05:12:40 INFO - 'log_name': 'talos', 05:12:40 INFO - 'log_to_console': True, 05:12:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:12:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:12:40 INFO - 'opt_config_files': (), 05:12:40 INFO - 'pip_index': False, 05:12:40 INFO - 'sps_profile': False, 05:12:40 INFO - 'sps_profile_interval': 0, 05:12:40 INFO - 'suite': 'dromaeojs', 05:12:40 INFO - 'system_bits': '32', 05:12:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:40 INFO - 'title': 'talos-linux64-ix-020', 05:12:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:40 INFO - 'use_talos_json': True, 05:12:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:40 INFO - 'work_dir': 'build'} 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.129337Z] Running clobber step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: clobber 05:12:40 INFO - rmtree: /builds/slave/test/build 05:12:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.590260Z] Finished clobber step (success) 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.590363Z] Running read-buildbot-config step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: read_buildbot_config 05:12:40 INFO - Using buildbot properties: 05:12:40 INFO - { 05:12:40 INFO - "project": "", 05:12:40 INFO - "product": "firefox", 05:12:40 INFO - "script_repo_revision": "production", 05:12:40 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:12:40 INFO - "repository": "", 05:12:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:12:40 INFO - "buildid": "20160910055116", 05:12:40 INFO - "pgo_build": "True", 05:12:40 INFO - "basedir": "/builds/slave/test", 05:12:40 INFO - "buildnumber": 461, 05:12:40 INFO - "slavename": "talos-linux64-ix-020", 05:12:40 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:12:40 INFO - "platform": "ubuntu64_hw", 05:12:40 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:12:40 INFO - "branch": "mozilla-aurora", 05:12:40 INFO - "repo_path": "releases/mozilla-aurora", 05:12:40 INFO - "revision": "8663d41e015f3c9933a08a65c8349b86f2263354", 05:12:40 INFO - "stage_platform": "linux64-pgo", 05:12:40 INFO - "builduid": "fb98bf9a800847589b1767e37e53bef3", 05:12:40 INFO - "slavebuilddir": "test" 05:12:40 INFO - } 05:12:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 05:12:40 INFO - Running post-action listener: _resource_record_post_action 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.592821Z] Finished read-buildbot-config step (success) 05:12:40 INFO - [mozharness: 2016-09-12 12:12:40.592911Z] Running download-and-extract step. 05:12:40 INFO - Running pre-action listener: _resource_record_pre_action 05:12:40 INFO - Running main action method: download_and_extract 05:12:40 INFO - mkdir: /builds/slave/test/build/tests 05:12: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')]} 05:12:40 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:12:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:12:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:12:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:12:40 INFO - retry: Failed, sleeping 30 seconds before retrying 05:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:13:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:13:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:13:10 INFO - retry: Failed, sleeping 60 seconds before retrying 05:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:14:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:14:10 INFO - Caught exception: HTTP Error 404: Not Found 05:14:10 INFO - Caught exception: HTTP Error 404: Not Found 05:14:10 INFO - Caught exception: HTTP Error 404: Not Found 05:14:10 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:11 INFO - Downloaded 1361 bytes. 05:14:11 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:11 INFO - Using the following test package requirements: 05:14:11 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:11 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:14:11 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:14:11 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'jsshell-linux-x86_64.zip'], 05:14:11 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:14:11 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:11 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:14:11 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:14:11 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:14:11 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:11 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:14:11 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:14: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')]} 05:14:11 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:14:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:14:11 INFO - retry: Failed, sleeping 30 seconds before retrying 05:14:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:14:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:14:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:14:41 INFO - retry: Failed, sleeping 60 seconds before retrying 05:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:15:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:15:41 INFO - Caught exception: HTTP Error 404: Not Found 05:15:41 INFO - Caught exception: HTTP Error 404: Not Found 05:15:41 INFO - Caught exception: HTTP Error 404: Not Found 05:15:41 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:42 INFO - Downloaded 21770904 bytes. 05:15:42 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'] 05:15:42 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 05:15:43 INFO - Return code: 0 05:15:43 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 05:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:43 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:15:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:15:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:15:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:15:43 INFO - retry: Failed, sleeping 30 seconds before retrying 05:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:16:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:16:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:16:13 INFO - retry: Failed, sleeping 60 seconds before retrying 05:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:17:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:17:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:17:13 INFO - Caught exception: HTTP Error 404: Not Found 05:17:13 INFO - Caught exception: HTTP Error 404: Not Found 05:17:13 INFO - Caught exception: HTTP Error 404: Not Found 05:17:13 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 05:17:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:14 INFO - Downloaded 21770904 bytes. 05:17:14 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'] 05:17:14 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 05:17:15 INFO - Return code: 0 05:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:15 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:17:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:17:15 INFO - retry: Failed, sleeping 30 seconds before retrying 05:17:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:17:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:17:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:17:45 INFO - retry: Failed, sleeping 60 seconds before retrying 05:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:18:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:18:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:18:45 INFO - Caught exception: HTTP Error 404: Not Found 05:18:45 INFO - Caught exception: HTTP Error 404: Not Found 05:18:45 INFO - Caught exception: HTTP Error 404: Not Found 05:18:45 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 05:18:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:46 INFO - Downloaded 14413740 bytes. 05:18:46 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'] 05:18:46 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 05:18:46 INFO - Return code: 0 05:18: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')]} 05:18:46 INFO - https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:18:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:18:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:18:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:18:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:18:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:18:46 INFO - retry: Failed, sleeping 30 seconds before retrying 05:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:19:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:19:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:19:16 INFO - retry: Failed, sleeping 60 seconds before retrying 05:20:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:20:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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! 05:20:17 INFO - Caught exception: HTTP Error 404: Not Found 05:20:17 INFO - Caught exception: HTTP Error 404: Not Found 05:20:17 INFO - Caught exception: HTTP Error 404: Not Found 05:20:17 INFO - trying https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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 05:20:18 INFO - Downloaded 63895992 bytes. 05:20:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:18 INFO - mkdir: /builds/slave/test/properties 05:20:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:18 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:18 INFO - Contents: 05:20:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 05:20:18 INFO - Running post-action listener: _resource_record_post_action 05:20:18 INFO - Running post-action listener: set_extra_try_arguments 05:20:18 INFO - [mozharness: 2016-09-12 12:20:18.896372Z] Finished download-and-extract step (success) 05:20:18 INFO - [mozharness: 2016-09-12 12:20:18.896609Z] Running populate-webroot step. 05:20:18 INFO - Running pre-action listener: _resource_record_pre_action 05:20:18 INFO - Running main action method: populate_webroot 05:20:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:18 INFO - '--tppagecycles', 05:20:18 INFO - '7'], 05:20:18 INFO - 'tests': ['tsvgm']}}, 05:20:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['damp', 'tps']}, 05:20:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'tests': ['damp', 'tps']}, 05:20:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['dromaeo_dom']}, 05:20:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['basic_compositor_video']}, 05:20:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['a11yr', 05:20:18 INFO - 'ts_paint', 05:20:18 INFO - 'tpaint', 05:20:18 INFO - 'sessionrestore', 05:20:18 INFO - 'sessionrestore_no_auto_restore', 05:20:18 INFO - 'tabpaint']}, 05:20:18 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:18 INFO - 'ts_paint', 05:20:18 INFO - 'tpaint', 05:20:18 INFO - 'sessionrestore', 05:20:18 INFO - 'sessionrestore_no_auto_restore', 05:20:18 INFO - 'tabpaint']}, 05:20:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:18 INFO - 'ts_paint', 05:20:18 INFO - 'tpaint', 05:20:18 INFO - 'sessionrestore', 05:20:18 INFO - 'sessionrestore_no_auto_restore', 05:20:18 INFO - 'tabpaint']}, 05:20:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:18 INFO - 'ts_paint', 05:20:18 INFO - 'tpaint', 05:20:18 INFO - 'sessionrestore', 05:20:18 INFO - 'sessionrestore_no_auto_restore', 05:20:18 INFO - 'tabpaint']}, 05:20:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['a11yr', 05:20:18 INFO - 'ts_paint', 05:20:18 INFO - 'tpaint', 05:20:18 INFO - 'sessionrestore', 05:20:18 INFO - 'sessionrestore_no_auto_restore', 05:20:18 INFO - 'tabpaint']}, 05:20:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['a11yr', 05:20:18 INFO - 'ts_paint', 05:20:18 INFO - 'tpaint', 05:20:18 INFO - 'sessionrestore', 05:20:18 INFO - 'sessionrestore_no_auto_restore', 05:20:18 INFO - 'tabpaint']}, 05:20:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['tsvgx', 05:20:18 INFO - 'tsvgr_opacity', 05:20:18 INFO - 'tart', 05:20:18 INFO - 'tscrollx', 05:20:18 INFO - 'cart']}, 05:20:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:18 INFO - 'tsvgr_opacity', 05:20:18 INFO - 'tart', 05:20:18 INFO - 'tscrollx', 05:20:18 INFO - 'cart']}, 05:20:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'talos_options': ['--disable-e10s'], 05:20:18 INFO - 'tests': ['tp5o']}, 05:20:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'tests': ['tp5o']}, 05:20:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'talos_options': ['--disable-e10s', 05:20:18 INFO - '--xperf_path', 05:20:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:18 INFO - 'tests': ['tp5n']}, 05:20:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:18 INFO - 'talos_options': ['--xperf_path', 05:20:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:18 INFO - 'tests': ['tp5n']}}, 05:20:18 INFO - 'talos.zip': {'path': '', 05:20:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:18 INFO - Running post-action listener: _resource_record_post_action 05:20:18 INFO - [mozharness: 2016-09-12 12:20:18.912042Z] Finished populate-webroot step (success) 05:20:18 INFO - [mozharness: 2016-09-12 12:20:18.912132Z] Running create-virtualenv step. 05:20:18 INFO - Running pre-action listener: _resource_record_pre_action 05:20:18 INFO - Running main action method: create_virtualenv 05:20:18 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:18 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 05:20:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:19 INFO - Using real prefix '/usr' 05:20:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:21 INFO - Installing pip.................done. 05:20:21 INFO - Return code: 0 05:20:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:21 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 05:20:21 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 05:20:21 INFO - Using env: {'DISPLAY': ':0', 05:20:21 INFO - 'HOME': '/home/cltbld', 05:20:21 INFO - 'LANG': 'en_US.UTF-8', 05:20:21 INFO - 'LANGUAGE': 'en_US:en', 05:20:21 INFO - 'LOGNAME': 'cltbld', 05:20:21 INFO - 'MAIL': '/var/mail/cltbld', 05:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:21 INFO - 'MOZ_NO_REMOTE': '1', 05:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:21 INFO - 'NO_EM_RESTART': '1', 05:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:21 INFO - 'PWD': '/builds/slave/test', 05:20:21 INFO - 'SHELL': '/bin/bash', 05:20:21 INFO - 'SHLVL': '1', 05:20:21 INFO - 'TERM': 'linux', 05:20:21 INFO - 'TMOUT': '86400', 05:20:21 INFO - 'USER': 'cltbld', 05:20:21 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:21 INFO - '_': '/tools/buildbot/bin/python'} 05:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:21 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 05:20:21 INFO - Cleaning up... 05:20:21 INFO - Return code: 0 05:20:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:21 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:21 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 05:20:21 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 05:20:21 INFO - Using env: {'DISPLAY': ':0', 05:20:21 INFO - 'HOME': '/home/cltbld', 05:20:21 INFO - 'LANG': 'en_US.UTF-8', 05:20:21 INFO - 'LANGUAGE': 'en_US:en', 05:20:21 INFO - 'LOGNAME': 'cltbld', 05:20:21 INFO - 'MAIL': '/var/mail/cltbld', 05:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:21 INFO - 'MOZ_NO_REMOTE': '1', 05:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:21 INFO - 'NO_EM_RESTART': '1', 05:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:21 INFO - 'PWD': '/builds/slave/test', 05:20:21 INFO - 'SHELL': '/bin/bash', 05:20:21 INFO - 'SHLVL': '1', 05:20:21 INFO - 'TERM': 'linux', 05:20:21 INFO - 'TMOUT': '86400', 05:20:21 INFO - 'USER': 'cltbld', 05:20:21 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:21 INFO - '_': '/tools/buildbot/bin/python'} 05:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:21 INFO - Downloading/unpacking psutil>=3.1.1 05:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:23 INFO - Installing collected packages: psutil 05:20:23 INFO - Running setup.py install for psutil 05:20:23 INFO - building 'psutil._psutil_linux' extension 05:20:23 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 05:20:24 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 05:20:24 INFO - building 'psutil._psutil_posix' extension 05:20:24 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 05:20:24 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 05:20:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:24 INFO - Successfully installed psutil 05:20:24 INFO - Cleaning up... 05:20:24 INFO - Return code: 0 05:20:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20:24 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')]} 05:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:24 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')]} 05:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:24 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:24 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 05:20:24 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 05:20:24 INFO - Using env: {'DISPLAY': ':0', 05:20:24 INFO - 'HOME': '/home/cltbld', 05:20:24 INFO - 'LANG': 'en_US.UTF-8', 05:20:24 INFO - 'LANGUAGE': 'en_US:en', 05:20:24 INFO - 'LOGNAME': 'cltbld', 05:20:24 INFO - 'MAIL': '/var/mail/cltbld', 05:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:24 INFO - 'MOZ_NO_REMOTE': '1', 05:20:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:24 INFO - 'NO_EM_RESTART': '1', 05:20:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:24 INFO - 'PWD': '/builds/slave/test', 05:20:24 INFO - 'SHELL': '/bin/bash', 05:20:24 INFO - 'SHLVL': '1', 05:20:24 INFO - 'TERM': 'linux', 05:20:24 INFO - 'TMOUT': '86400', 05:20:24 INFO - 'USER': 'cltbld', 05:20:24 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:24 INFO - '_': '/tools/buildbot/bin/python'} 05:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:24 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 05:20:24 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 05:20:24 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 05:20:24 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 05:20:24 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 05:20:24 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 05:20:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:20:26 INFO - Installing collected packages: mozsystemmonitor 05:20:26 INFO - Running setup.py install for mozsystemmonitor 05:20:26 INFO - Successfully installed mozsystemmonitor 05:20:26 INFO - Cleaning up... 05:20:26 INFO - Return code: 0 05:20:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:26 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')]} 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:26 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')]} 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:26 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:26 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 05:20:26 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 05:20:26 INFO - Using env: {'DISPLAY': ':0', 05:20:26 INFO - 'HOME': '/home/cltbld', 05:20:26 INFO - 'LANG': 'en_US.UTF-8', 05:20:26 INFO - 'LANGUAGE': 'en_US:en', 05:20:26 INFO - 'LOGNAME': 'cltbld', 05:20:26 INFO - 'MAIL': '/var/mail/cltbld', 05:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:26 INFO - 'MOZ_NO_REMOTE': '1', 05:20:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:26 INFO - 'NO_EM_RESTART': '1', 05:20:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:26 INFO - 'PWD': '/builds/slave/test', 05:20:26 INFO - 'SHELL': '/bin/bash', 05:20:26 INFO - 'SHLVL': '1', 05:20:26 INFO - 'TERM': 'linux', 05:20:26 INFO - 'TMOUT': '86400', 05:20:26 INFO - 'USER': 'cltbld', 05:20:26 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:26 INFO - '_': '/tools/buildbot/bin/python'} 05:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:26 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:28 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 05:20:28 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 05:20:28 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 05:20:28 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 05:20:28 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 05:20:28 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 05:20:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:29 INFO - warning: no files found matching '*.txt' 05:20:29 INFO - no previously-included directories found matching 'build' 05:20:29 INFO - no previously-included directories found matching 'dist' 05:20:29 INFO - no previously-included directories found matching '.git*' 05:20:29 INFO - Installing collected packages: jsonschema, functools32 05:20:29 INFO - Running setup.py install for functools32 05:20:29 INFO - warning: no files found matching '*.txt' 05:20:29 INFO - no previously-included directories found matching 'build' 05:20:29 INFO - no previously-included directories found matching 'dist' 05:20:29 INFO - no previously-included directories found matching '.git*' 05:20:29 INFO - Successfully installed jsonschema functools32 05:20:29 INFO - Cleaning up... 05:20:29 INFO - Return code: 0 05:20:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:20:29 INFO - Using env: {'DISPLAY': ':0', 05:20:29 INFO - 'HOME': '/home/cltbld', 05:20:29 INFO - 'LANG': 'en_US.UTF-8', 05:20:29 INFO - 'LANGUAGE': 'en_US:en', 05:20:29 INFO - 'LOGNAME': 'cltbld', 05:20:29 INFO - 'MAIL': '/var/mail/cltbld', 05:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:29 INFO - 'MOZ_NO_REMOTE': '1', 05:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:29 INFO - 'NO_EM_RESTART': '1', 05:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:29 INFO - 'PWD': '/builds/slave/test', 05:20:29 INFO - 'SHELL': '/bin/bash', 05:20:29 INFO - 'SHLVL': '1', 05:20:29 INFO - 'TERM': 'linux', 05:20:29 INFO - 'TMOUT': '86400', 05:20:29 INFO - 'USER': 'cltbld', 05:20:29 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:29 INFO - '_': '/tools/buildbot/bin/python'} 05:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:29 INFO - Cleaning up... 05:20:29 INFO - Return code: 0 05:20:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:20:29 INFO - Using env: {'DISPLAY': ':0', 05:20:29 INFO - 'HOME': '/home/cltbld', 05:20:29 INFO - 'LANG': 'en_US.UTF-8', 05:20:29 INFO - 'LANGUAGE': 'en_US:en', 05:20:29 INFO - 'LOGNAME': 'cltbld', 05:20:29 INFO - 'MAIL': '/var/mail/cltbld', 05:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:29 INFO - 'MOZ_NO_REMOTE': '1', 05:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:29 INFO - 'NO_EM_RESTART': '1', 05:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:29 INFO - 'PWD': '/builds/slave/test', 05:20:29 INFO - 'SHELL': '/bin/bash', 05:20:29 INFO - 'SHLVL': '1', 05:20:29 INFO - 'TERM': 'linux', 05:20:29 INFO - 'TMOUT': '86400', 05:20:29 INFO - 'USER': 'cltbld', 05:20:29 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:29 INFO - '_': '/tools/buildbot/bin/python'} 05:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:29 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 05:20:29 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 05:20:29 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 05:20:29 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 05:20:29 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 05:20:29 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 05:20:31 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 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 05:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:32 INFO - Downloading docopt-0.6.1.tar.gz 05:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:32 INFO - Running setup.py install for blobuploader 05:20:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:32 INFO - Running setup.py install for requests 05:20:32 INFO - Running setup.py install for docopt 05:20:32 INFO - Successfully installed blobuploader requests docopt 05:20:32 INFO - Cleaning up... 05:20:32 INFO - Return code: 0 05:20:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:32 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')]} 05:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:32 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')]} 05:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:32 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:32 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 05:20:32 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 05:20:32 INFO - Using env: {'DISPLAY': ':0', 05:20:32 INFO - 'HOME': '/home/cltbld', 05:20:32 INFO - 'LANG': 'en_US.UTF-8', 05:20:32 INFO - 'LANGUAGE': 'en_US:en', 05:20:32 INFO - 'LOGNAME': 'cltbld', 05:20:32 INFO - 'MAIL': '/var/mail/cltbld', 05:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:32 INFO - 'MOZ_NO_REMOTE': '1', 05:20:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:32 INFO - 'NO_EM_RESTART': '1', 05:20:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:32 INFO - 'PWD': '/builds/slave/test', 05:20:32 INFO - 'SHELL': '/bin/bash', 05:20:32 INFO - 'SHLVL': '1', 05:20:32 INFO - 'TERM': 'linux', 05:20:32 INFO - 'TMOUT': '86400', 05:20:32 INFO - 'USER': 'cltbld', 05:20:32 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:32 INFO - '_': '/tools/buildbot/bin/python'} 05:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:33 INFO - Running setup.py (path:/tmp/pip-ANOg6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:33 INFO - Running setup.py (path:/tmp/pip-BvWZsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:33 INFO - Running setup.py (path:/tmp/pip-pl2lf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:33 INFO - Running setup.py (path:/tmp/pip-0dpCbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:33 INFO - Running setup.py (path:/tmp/pip-oYxlhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:33 INFO - Running setup.py (path:/tmp/pip-BJgQz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:33 INFO - Running setup.py (path:/tmp/pip-25ua9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:33 INFO - Running setup.py (path:/tmp/pip-Qlt_gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:33 INFO - Running setup.py (path:/tmp/pip-z7dUgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:33 INFO - Running setup.py (path:/tmp/pip-Rc34WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:33 INFO - Running setup.py (path:/tmp/pip-ZvypGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:34 INFO - Running setup.py (path:/tmp/pip-Nzwy8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:34 INFO - Running setup.py (path:/tmp/pip-jlz0BB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:34 INFO - Running setup.py (path:/tmp/pip-pBHjyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:34 INFO - Running setup.py (path:/tmp/pip-a2BPPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:34 INFO - Running setup.py (path:/tmp/pip-HX0quQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:34 INFO - Running setup.py (path:/tmp/pip-7KeB76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:34 INFO - Running setup.py install for manifestparser 05:20:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:34 INFO - Running setup.py install for mozcrash 05:20:34 INFO - Running setup.py install for mozdebug 05:20:34 INFO - Running setup.py install for mozdevice 05:20:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:34 INFO - Running setup.py install for mozfile 05:20:34 INFO - Running setup.py install for mozhttpd 05:20:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozinfo 05:20:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozInstall 05:20:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozleak 05:20:35 INFO - Running setup.py install for mozlog 05:20:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for moznetwork 05:20:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozprocess 05:20:35 INFO - Running setup.py install for mozprofile 05:20:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozrunner 05:20:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:35 INFO - Running setup.py install for mozscreenshot 05:20:36 INFO - Running setup.py install for moztest 05:20:36 INFO - Running setup.py install for mozversion 05:20:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:36 INFO - Cleaning up... 05:20:36 INFO - Return code: 0 05:20:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:36 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')]} 05:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:36 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')]} 05:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:36 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:36 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 05:20:36 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 05:20:36 INFO - Using env: {'DISPLAY': ':0', 05:20:36 INFO - 'HOME': '/home/cltbld', 05:20:36 INFO - 'LANG': 'en_US.UTF-8', 05:20:36 INFO - 'LANGUAGE': 'en_US:en', 05:20:36 INFO - 'LOGNAME': 'cltbld', 05:20:36 INFO - 'MAIL': '/var/mail/cltbld', 05:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:36 INFO - 'MOZ_NO_REMOTE': '1', 05:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:36 INFO - 'NO_EM_RESTART': '1', 05:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:36 INFO - 'PWD': '/builds/slave/test', 05:20:36 INFO - 'SHELL': '/bin/bash', 05:20:36 INFO - 'SHLVL': '1', 05:20:36 INFO - 'TERM': 'linux', 05:20:36 INFO - 'TMOUT': '86400', 05:20:36 INFO - 'USER': 'cltbld', 05:20:36 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:36 INFO - '_': '/tools/buildbot/bin/python'} 05:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:36 INFO - Running setup.py (path:/tmp/pip-CGUYfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:36 INFO - Running setup.py (path:/tmp/pip-8k8pVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:36 INFO - Running setup.py (path:/tmp/pip-HkQlrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:36 INFO - Running setup.py (path:/tmp/pip-F226U5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:36 INFO - Running setup.py (path:/tmp/pip-32iAWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:36 INFO - Running setup.py (path:/tmp/pip-XtpWM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:36 INFO - Running setup.py (path:/tmp/pip-cf8auB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:36 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)) 05:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:36 INFO - Running setup.py (path:/tmp/pip-1RIKPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:37 INFO - Running setup.py (path:/tmp/pip-1z7NF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:37 INFO - Running setup.py (path:/tmp/pip-3352i1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:37 INFO - Running setup.py (path:/tmp/pip-lOgCvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:37 INFO - Running setup.py (path:/tmp/pip-kAioJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:37 INFO - Running setup.py (path:/tmp/pip-YUnv2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:37 INFO - Running setup.py (path:/tmp/pip-KjQGbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:37 INFO - Running setup.py (path:/tmp/pip-smJRxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:37 INFO - Running setup.py (path:/tmp/pip-tts98L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:37 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)) 05:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:37 INFO - Running setup.py (path:/tmp/pip-iQDUDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:37 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)) 05:20:37 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)) 05:20:37 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)) 05:20:37 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)) 05:20:37 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)) 05:20:37 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)) 05:20:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20: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 05:20:39 INFO - Downloading blessings-1.6.tar.gz 05:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:39 INFO - Installing collected packages: blessings 05:20:39 INFO - Running setup.py install for blessings 05:20:39 INFO - Successfully installed blessings 05:20:39 INFO - Cleaning up... 05:20:39 INFO - Return code: 0 05:20:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:40 INFO - Reading from file tmpfile_stdout 05:20:40 INFO - Current package versions: 05:20:40 INFO - argparse == 1.2.1 05:20:40 INFO - blessings == 1.6 05:20:40 INFO - blobuploader == 1.2.4 05:20:40 INFO - docopt == 0.6.1 05:20:40 INFO - functools32 == 3.2.3-2 05:20:40 INFO - jsonschema == 2.5.1 05:20:40 INFO - manifestparser == 1.1 05:20:40 INFO - mozInstall == 1.12 05:20:40 INFO - mozcrash == 0.17 05:20:40 INFO - mozdebug == 0.1 05:20:40 INFO - mozdevice == 0.48 05:20:40 INFO - mozfile == 1.2 05:20:40 INFO - mozhttpd == 0.7 05:20:40 INFO - mozinfo == 0.9 05:20:40 INFO - mozleak == 0.1 05:20:40 INFO - mozlog == 3.3 05:20:40 INFO - moznetwork == 0.27 05:20:40 INFO - mozprocess == 0.23 05:20:40 INFO - mozprofile == 0.28 05:20:40 INFO - mozrunner == 6.12 05:20:40 INFO - mozscreenshot == 0.1 05:20:40 INFO - mozsystemmonitor == 0.3 05:20:40 INFO - moztest == 0.7 05:20:40 INFO - mozversion == 1.4 05:20:40 INFO - psutil == 3.1.1 05:20:40 INFO - requests == 1.2.3 05:20:40 INFO - wsgiref == 0.1.2 05:20:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:40 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 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20:40 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 05:20:40 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 05:20:40 INFO - Using env: {'DISPLAY': ':0', 05:20:40 INFO - 'HOME': '/home/cltbld', 05:20:40 INFO - 'LANG': 'en_US.UTF-8', 05:20:40 INFO - 'LANGUAGE': 'en_US:en', 05:20:40 INFO - 'LOGNAME': 'cltbld', 05:20:40 INFO - 'MAIL': '/var/mail/cltbld', 05:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:40 INFO - 'MOZ_NO_REMOTE': '1', 05:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:40 INFO - 'NO_EM_RESTART': '1', 05:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:40 INFO - 'PWD': '/builds/slave/test', 05:20:40 INFO - 'SHELL': '/bin/bash', 05:20:40 INFO - 'SHLVL': '1', 05:20:40 INFO - 'TERM': 'linux', 05:20:40 INFO - 'TMOUT': '86400', 05:20:40 INFO - 'USER': 'cltbld', 05:20:40 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 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)) 05:20:40 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20: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')]} 05:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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')]} 05:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e3030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17468a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a4180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, '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': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', '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 05:20: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'] in /builds/slave/test/build 05:20: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 05:20:40 INFO - Using env: {'DISPLAY': ':0', 05:20:40 INFO - 'HOME': '/home/cltbld', 05:20:40 INFO - 'LANG': 'en_US.UTF-8', 05:20:40 INFO - 'LANGUAGE': 'en_US:en', 05:20:40 INFO - 'LOGNAME': 'cltbld', 05:20:40 INFO - 'MAIL': '/var/mail/cltbld', 05:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:40 INFO - 'MOZ_NO_REMOTE': '1', 05:20:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:40 INFO - 'NO_EM_RESTART': '1', 05:20:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:40 INFO - 'PWD': '/builds/slave/test', 05:20:40 INFO - 'SHELL': '/bin/bash', 05:20:40 INFO - 'SHLVL': '1', 05:20:40 INFO - 'TERM': 'linux', 05:20:40 INFO - 'TMOUT': '86400', 05:20:40 INFO - 'USER': 'cltbld', 05:20:40 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:40 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Running post-action listener: _resource_record_post_action 05:20:40 INFO - Running post-action listener: _start_resource_monitoring 05:20:40 INFO - Starting resource monitoring. 05:20:40 INFO - [mozharness: 2016-09-12 12:20:40.491834Z] Finished create-virtualenv step (success) 05:20:40 INFO - [mozharness: 2016-09-12 12:20:40.492328Z] Running install step. 05:20:40 INFO - Running pre-action listener: _resource_record_pre_action 05:20:40 INFO - Running main action method: install 05:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:40 INFO - Reading from file tmpfile_stdout 05:20:40 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:20:40 INFO - Reading from file tmpfile_stdout 05:20:40 INFO - Output received: 05:20:40 INFO - Usage: mozinstall [options] installer 05:20:40 INFO - Options: 05:20:40 INFO - -h, --help show this help message and exit 05:20:40 INFO - -d DEST, --destination=DEST 05:20:40 INFO - Directory to install application into. [default: 05:20:40 INFO - "/builds/slave/test"] 05:20:40 INFO - --app=APP Application being installed. [default: firefox] 05:20:40 INFO - mkdir: /builds/slave/test/build/application 05:20:40 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'] 05:20:40 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 05:20:56 INFO - Reading from file tmpfile_stdout 05:20:56 INFO - Output received: 05:20:56 INFO - /builds/slave/test/build/application/firefox/firefox 05:20:56 INFO - Running post-action listener: _resource_record_post_action 05:20:56 INFO - [mozharness: 2016-09-12 12:20:56.802349Z] Finished install step (success) 05:20:56 INFO - [mozharness: 2016-09-12 12:20:56.802628Z] Running run-tests step. 05:20:56 INFO - Running pre-action listener: _resource_record_pre_action 05:20:56 INFO - Running main action method: run_tests 05:20:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:20:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:20:56 INFO - Python 2.7.3 05:20:56 INFO - Return code: 0 05:20:56 INFO - grabbing minidump binary from tooltool 05:20:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:56 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 0x18a23b0>, '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 0x18a32b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a3740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:20:56 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 05:20:56 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 05:20:56 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 05:20:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:20:56 INFO - Return code: 0 05:20:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:20:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:20:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:20:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:20:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:20:56 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 05:20:56 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 05:20:56 INFO - Using env: {'DISPLAY': ':0', 05:20:56 INFO - 'HOME': '/home/cltbld', 05:20:56 INFO - 'LANG': 'en_US.UTF-8', 05:20:56 INFO - 'LANGUAGE': 'en_US:en', 05:20:56 INFO - 'LOGNAME': 'cltbld', 05:20:56 INFO - 'MAIL': '/var/mail/cltbld', 05:20:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:56 INFO - 'MOZ_NO_REMOTE': '1', 05:20:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:56 INFO - 'NO_EM_RESTART': '1', 05:20:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:56 INFO - 'PWD': '/builds/slave/test', 05:20:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:56 INFO - 'SHELL': '/bin/bash', 05:20:56 INFO - 'SHLVL': '1', 05:20:56 INFO - 'TERM': 'linux', 05:20:56 INFO - 'TMOUT': '86400', 05:20:56 INFO - 'USER': 'cltbld', 05:20:56 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800', 05:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:56 INFO - '_': '/tools/buildbot/bin/python'} 05:20:56 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/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 05:20:57 INFO - mozversion application_buildid: 20160910055116 05:20:57 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:20:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:20:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:57 INFO - mozversion application_name: Firefox 05:20:57 INFO - mozversion application_remotingname: firefox-dev 05:20:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:57 INFO - mozversion application_vendor: Mozilla 05:20:57 INFO - mozversion application_version: 50.0a2 05:20:57 INFO - mozversion platform_buildid: 20160910055116 05:20:57 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:20:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:57 INFO - mozversion platform_version: 50.0a2 05:20:57 INFO - using testdate: 1473682857 05:20:57 INFO - actual date: 1473682857 05:20:57 INFO - starting webserver on 'localhost:33320' 05:20:57 INFO - SUITE-START | Running 2 tests 05:20:57 INFO - TEST-START | dromaeo_css 05:20:57 INFO - operating with platform_type : linux_ 05:20:57 INFO - Initialising browser for dromaeo_css test... 05:20:57 INFO - TEST-INFO | started process 3007 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4_IOBf/profile http://localhost:33320/getInfo.html) 05:21:01 INFO - PROCESS | 3007 | __metrics Screen width/height:1600/1200 05:21:01 INFO - PROCESS | 3007 | colorDepth:24 05:21:01 INFO - PROCESS | 3007 | Browser inner width/height: 1024/697 05:21:01 INFO - PROCESS | 3007 | __metrics 05:21:02 INFO - PROCESS | 3007 | 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" 05:21:02 INFO - PROCESS | 3007 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:21:03 INFO - TEST-INFO | 3007: exit 0 05:21:03 INFO - Browser initialized. 05:21:03 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:03 INFO - TEST-INFO | started process 3093 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4_IOBf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:14 INFO - PROCESS | 3093 | RSS: Main: 145494016 05:21:14 INFO - PROCESS | 3093 | 05:22:05 INFO - PROCESS | 3093 | 1473682925646 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] 05:24:14 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:33320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33320/tests/dromaeo/cssquery-ext.html) 05:24:15 INFO - PROCESS | 3093 | RSS: Main: 279597056 05:24:15 INFO - PROCESS | 3093 | 05:27:18 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:33320/tests/dromaeo/cssquery-ext.html (next: http://localhost:33320/tests/dromaeo/cssquery-jquery.html) 05:27:19 INFO - PROCESS | 3093 | RSS: Main: 232738816 05:27:19 INFO - PROCESS | 3093 | 05:30:20 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:33320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33320/tests/dromaeo/cssquery-mootools.html) 05:30:21 INFO - PROCESS | 3093 | RSS: Main: 175390720 05:30:21 INFO - PROCESS | 3093 | 05:33:23 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:33320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33320/tests/dromaeo/cssquery-prototype.html) 05:33:23 INFO - PROCESS | 3093 | RSS: Main: 173490176 05:33:23 INFO - PROCESS | 3093 | 05:36:26 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:33320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33320/tests/dromaeo/cssquery-yui.html) 05:36:27 INFO - PROCESS | 3093 | RSS: Main: 177262592 05:36:27 INFO - PROCESS | 3093 | 05:39:29 INFO - PROCESS | 3093 | Cycle 1(1): loaded http://localhost:33320/tests/dromaeo/cssquery-yui.html (next: http://localhost:33320/tests/dromaeo/cssquery-dojo.html) 05:39:30 INFO - PROCESS | 3093 | RSS: Main: 266338304 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | __start_tp_report 05:39:30 INFO - PROCESS | 3093 | _x_x_mozilla_page_load 05:39:30 INFO - PROCESS | 3093 | _x_x_mozilla_page_load_details 05:39:30 INFO - PROCESS | 3093 | |i|pagename|runs| 05:39:30 INFO - PROCESS | 3093 | |0;dojo.html;3233.240188555689;3236.692514211149;3238.8056716597007;3280.294736631624;3298.538204651349;5517.420670829561;5614.522765564931;5615.2700148980575;5628.493435590796;5650.915236271459;5585.58108141889;5635.380108188096;5638.520725738307;5644.322681278242;5699.458551437617;5051.576687636034;5091.439941606411;5106.259592359106;5115.181570948649;5171.689698618095;5155.149400348938;5225.399079105913;5228.398734145565;5234.162533994561;5234.476552344773;5054.368203974504;5061.79752809889;5089.643724939256;5090.211017292307;5130.2817605535065;5164.354455693038;5186.247994040843;5187.636865419422;5188.662736922126;5220.556252718505;5015.3730783652045;5061.468545802674;5088.6946783193125;5090.669106508065;5100.821471248489;5522.585806064561;5526.696031718254;5527.723613819333;5543.584231182677;5624.662520248758;6027.939720602825;6028.577999540034;6037.426444487811;6039.969800151015;6043.788467403664;5849.268841394825;5939.406059394069;5958.314793798725;5969.731362088673;6074.301455332605;5579.525740312079;5580.944190558123;5597.692126933058;5636.4927156556105;5645.463680950304;6058.1518587397795;6114.877702445926;6116.571839971249;6116.969415152896;6123.4488895999575;5784.913226301609;5894.557908156906;5931.911021334683;5941.316748573883;6012.849678758066;6087.178230938855;6088.117223002729;6091.116788065794;6092.573519853657;6096.29892562361;6014.6391216527145;6025.457708806229;6028.216331876915;6035.094735789579;6040.607360521552;5816.040353341651;5855.560832937546;6010.188624535719;6034.758609655564;6057.818265452043;4652.534746525388;4687.695299805502;4689.038747056845;4716.693414928019;4724.078804633064;4160.043190066302;4168.228877657643;4173.8956526087095;4180.602842729936;4198.811053502539;5563.443655634404;5701.486866182064;5727.742251598688;5739.368669446358;5864.501517371071;6081.391860813883;6083.543734219951;6084.6349219047;6090.421409966135;6115.938840611626;5773.624714393551;5774.971125144431;5787.421257874179;5798.681072540966;5802.21670074543;5189.4032186299055;5199.428062913076;5229.555487783505;5246.396664383645;5257.447967963385;6304.7162877671535;6309.589876658002;6311.274203466568;6313.305536390993;6313.652749098752;6032.577719559764;6089.147519347383;6089.33017368098;6093.29927058394;6096.512279017579;5996.940030599638;6145.231846019247;6177;6181.412765787243;6319.9052014218905;5955.702214889325;5955.76176952917;5972.671503067373;5973.223480947449;5997.070454079625;6041.667708276087;6052.848678783065;6058.303295120942;6058.606190597597;6060.878782424239;5784.1034639630925;5796.014677504919;5799.623024503394;5842.503387212135;5916.556258280732;5819.359870414336;5862.179294898803;5863.970680146572;5874.236349274567;5892.852678683067;5770.221020162392;5827.9708601458415;5830.62100963436;5831.154482599914;5835.620684655484;5776.9133462998925;5843.1235314703135;5852.795152169653;5874.559408044499;5890.2342695449315;5847.298324201123;5856.150858125633;5857.824265271878;5877.23595932526;5893.882122357443 05:39:30 INFO - PROCESS | 3093 | |1;ext.html;588.6409290332896;640.836586670399;664.126673424447;665.5108495255989;666.3602941176471;34912.30175396494;37894.81052594737;37916.620833791654;38285.23429531411;38381.232375352476;38162.8091859541;47884.281735773904;48642.27036594454;48709.26936095962;53126.73436632811;9222.584983675733;9350.485723285217;9354.672586459475;9363.34456588039;9366.718998430058;8841.292696584258;9066.592003359865;9201.539923003824;9289.489078100702;9296.628134874596;8757.430767000125;8802.691905783298;11225;11477;11486.310821350704;11467.713307167345;11485.770284594344;11532.365719885403;11536.826947595793;11548.942255288712;8431;11156;11211.66365009051;11258.774824503545;11260.718482037933;44443;45025.77487112576;45233.321500177524;49312.75343623259;49366.50633493655;3845.8653947111716;3905.1994341159993;4130.524989626201;4325.913481730379;4512.481064677571;35195.82402087999;35554.28891422157;35830.82084589586;36051.4592281116;36056.27887442263;4765.237561990065;4781.665283430161;4791.448983366888;4801.639877009238;4815.614750819926;375889.1205543982;376068;377118;377169;377294;59032;59290.40709592849;59371.70314148402;59417;59454.40545594575;66790.66604667043;67496.32503674999;67726.322736772;68341;70737.2926270741;18775.15511801972;19017.80982190188;19018.144183511777;19033;19039.238430462625;14078;14150.43398264058;14176.007679462546;14184.007119501535;14188;17788.55528611795;19067.237310507702;19839.504012399804;19971.60056798885;20026.098825552886;6980.546264492792;7041.753538626123;7075.150981882207;7216.355740351194;7424.552212318654;1077.3374374759467;1144.730988217768;1168.3574034281112;1170.2042611024415;1206.541514224589;32725.769663648465;34137.65862341395;34738.82630586831;35018.474722879175;35233.47149792755;1176.8940795328463;1188.6849984754047;1196.9880301197052;1221.6335099470123;1254.6361555148906;17997;18296.085195740427;18367.35714249995;18382.356617518322;18484;618544;620489;621233;622082;625308;64025;64060;65133;65424.345756542774;65635;1446.674498237888;1448.5147475595793;1473.963150921214;1481.282651595369;1486.8067151270266;35089.64910350864;36802.86695653913;36843.815780922;36923.07692307714;36967.44548831823;57547;57846;57967.71016144892;58179;58269.70865145647;367740;369806;372556;374406;375100;45686.77156614329;46193.07613847637;46440.767796160806;46668.7666561665;47004.52995470002;51756.741216293674;52083.479165207864;52946;53253.201201982774;53710.194347085606;43897.78051109724;44061.559384405744;45420.772896135306;46675;46973.530264696914;41618.791906041486;44864.65147191826;46698;47285.29072063853;47954.280685790436;45016.09967800691;45886;46174.53825461702;46318.53681463277;46397.53602464067;38158.61841381551;39970.800145999085;40550.79724601358;40595.7970210147;40724.17465388641;30599;30611.846940765154;30626.387472250877;30682.84658576693;31133.53299700461 05:39:30 INFO - PROCESS | 3093 | |2;jquery.html;390.50600989747954;402.65774092021786;423.9830406783728;425.109395815766;434.2682579852948;14763.704725905476;19809.900950495226;20197.39407817766;20204.39386818396;21585.352439426842;20823.479413014684;20833.68749468759;20841.166353345885;20846.89576552119;21117.577648447023;5823.563232757539;5856.297244330686;5987.28152621686;6024.578279520435;6026.9095963560585;5838.4453476919625;5847.532197424196;5999;6006.969965150168;6019.337872833985;5880.7353669085;5980.342162362137;5994.43052909975;5994.940050599482;5995.070764031582;5748.425157484218;5920.851978700523;5974.671393073401;5978.40215978403;5978.671173085479;5335.439778823206;5336.866578335573;5344.545713614348;5345.679259244458;5345.893082138375;23591.410214744596;23742.40643983897;23870.880645596662;24156.63765043526;24781.62827557588;2497.862617556034;2554.4380236347974;2598.944459409571;2698.1096238241335;2992.1173253890156;14877.404903803836;15081.54755357341;15110.39558417662;15491.922540387339;15810.288537015862;1974.4076776966851;1982.2368388170514;2034.674452087659;2046.8771873687479;2091.215794077221;50482.49517504815;65179;85673.57163214144;86277;88228.55885720531;11307.77728347417;11399.829002564968;11403.258788178742;11454.885451145548;11611.129165312635;15932.167800702835;15970.76043859343;15976;16005.279762410719;16009.199540022952;8397.328213742885;8750.912490875136;8793.428427152214;8877.422967507091;8938.955305223431;8470.195331443503;8476.957615211884;8485.193906578865;8681.479111253218;8717.52053637056;6899.275576064541;6966.12923384577;7135.215126336099;7184.820379490555;7185.568865868065;1838.945125997173;1931.381957773499;2042.9182832686824;2099.4751312171957;2100.821430178452;991.2393982757984;1036.1451802263164;1091.9071878890384;1094.261373572835;1128.3230061962756;21063.8946805265;21188.04653790583;21312.680309795367;21358.572828543343;21361.25235616746;1031.7936412717486;1087.1085709718113;1108.0969010256615;1115.5649296774266;1127.1602656021316;11589.6523104307;12198.32909190003;12245.755084898252;12268.141230113984;12348.197367171106;520799;522276;525697;533752;535419;84982;87594.56202718946;88117.55941220382;92502.0749792507;92809.35953202313;1042.9061384475433;1043.4730461117088;1052.310736467615;1055.2402270365324;1060.9257351985286;17568.648627027644;18290.542736431697;18493.167807448965;18574.535636609195;18898.433047008613;41239.587604124776;41539.1692166161;42383.7880810594;42960.78519607382;46759;61234.69382653236;62089.37910620836;63513;63745;63945;7937;8301.58492075406;8396.832063358821;8754.387192896436;9232.169104780602;9902.405855648683;11213.775724485628;13861.23763193034;14009.859901400856;14053.36759845789;11809;11888.405579721153;11925.642230732745;12166.635000949986;12196.390180491117;11587.8261826071;11672.416379180837;11766.411679415824;11799.233049851731;12499.812502812283;11978.161528692908;12083.818742719042;12182.817257741319;12194.207376520853;12777.361131943551;8178.059523154913;8208.917910820815;8296.170382961656;8321.625526851427;8621.82756344857;10435.426051567236;11705.765884682089;11803;11863.34751588671;12178.512859485521 05:39:30 INFO - PROCESS | 3093 | |3;mootools.html;1510.1995942150656;1549.1402271739184;1707.2658756734606;1849.2418108575487;1860.051373799362;8884.511351875644;9585.424874507517;9586.472743999077;9602.855957160647;9606.0393960604;8245.876311855327;9118.635254589808;9283.767905802359;9413;9432.622695092205;2500.074972260262;2533.366658335416;2537.695476542808;2538.1877799104295;2538.2512158913073;2382.702162229721;2407.0492155598527;2407.6870006899167;2412.0110754590623;2416.5046165536023;2421.83047186697;2435.7198922123916;2490.8754562271906;2494.6008638617823;2496.10140349474;2375.0618505690327;2392.6530653055297;2393;2448.424620214248;2450.8529488230765;2424.91512797053;2441.8046556275453;2443.914462993787;2446.2294377271282;2501.474690315018;9831.705048848475;9897.802043959153;9908.603655853758;9912.058354456316;9938.503074846229;5414.539764120054;5500.862478438031;5510.8071217507595;5515.227868098429;5541.445855414467;3221.194701324669;3291.416238252984;3344.6488118747425;3352.178716214543;3371.359441706068;1208.383724300613;1211.9878801211962;1234.2471092633575;1282.3011458754909;1282.3203702037936;12623.936880315632;12634.431450584745;12644.747105057848;12679.429425675775;12699.174553653984;4763.809447622057;4834.226523756182;4906.773306673332;4998.050370429607;5001.974990125026;10574.788504229873;10575.947120264349;10600.310979786289;10603.416812075257;10609.57561697539;5466.972665136649;5471.945280547223;5474.042042642522;5474.972625136849;5476.479734425223;4637.489876113625;4892.143874821892;4966;5004;5117.2068329409;4169.666426685858;4232.640225580799;4325.52419233884;4332.740035597874;4548.044910568818;1344.9596512104652;1362.693393986365;1368.1107280267906;1382.536850155189;1383.0871624727833;1075.499892549968;1086.9239153259355;1090.8963648453384;1091.4433638844089;1095.7534554725123;6329.177206963157;6391.58454700443;6580.177477815273;6635.668216589152;6857.142857142857;493.5705935835846;504.1328914267454;507.5077175140182;511.98464046078675;514.8275327765166;4263.76549289792;4279.486461624562;4329.848455304049;4363.629091527192;4408.096340250241;10791.730206744895;11225.831612525819;11318.660440186808;11352.545898164146;11355.772884542263;10937;11274.210805243718;11405;11421.1434142438;11640.650780476599;8543;8755.387122901464;8764;8789;8830.64677412909;6267.780627678009;6574.211094668671;6618.371254730793;6691.230508491586;6792;10515.316504427188;10834.458277086273;10848.45757712127;10962.945185274022;11048.668539943814;11035.668929932115;11047.116230701686;11138.832917506083;11143.498542565441;11161.218714689894;7334.853302934018;8093.55485448306;8295.211954864279;8467.364947629076;8782.077881822317;9258.305627077862;9267.907320926704;9303.488308143116;9317.953410232905;9326.067393260892;8039.799005024921;8320.542370169698;8322.958385208036;8402;8618.655253789779;7946.92053079485;7964.482308649919;8306;8320.916790832014;8357.122502137432;7346.926530734624;7361.079865016873;7574.431917606092;7612.276833700789;7636.961815190888;5729.512991395778;5812;5818.650880947157;5823.79616713413;6008.939910600838;5720.713964301852;5793.391693872083;5842.561807864342;5879.794207202727;5918.496927761188 05:39:30 INFO - PROCESS | 3093 | |4;prototype.html;347.62109300862056;354.46652787554734;356.5756749468134;357.0983267250193;359.08075327162464;5444.863878403042;5456.3358175756375;5477.561795056396;5480.698561579122;5481.177823326503;5387.78448862046;5395.460453954603;5404.621676482648;5412.564346044276;5422.9728851355685;3807.4859893914245;4086.6117718816663;4260.552641972595;4274.893127671818;4293.463317085364;4286.97856510717;4302.806373713175;4305.763183024932;4307.655387569002;4337.652987760972;4077.2049450357194;4250.277452833026;4250.936235956463;4273.786310684469;4310.374995625632;4204.2432362174795;4290.184864875664;4295.656347492193;4305.461817272841;4323.2434323993175;3819.9181446111934;3829.8468061277517;3840.7311488195837;3846.7114966377635;3852.248811481762;5190.313148226842;5334.653247538937;5342.27879236302;5346.599005074635;5355.357357117132;4869.099216644897;4882.609391248727;4891.584215341701;4908;4909.754512274408;5660.830175094754;5672.8014519492035;5688.345840228385;5688.772449102031;5746.856328591819;3966.365381538969;4149.751014939114;4203.159368126387;4204.066129580397;4218.346156345771;5582.506082603502;5726.513246374063;5879.265091863517;5898.528117750612;5902.61632993854;4176.7076304658385;4185.497740271126;4211.410402543646;4268.125034367947;4274.722143060691;4295.914081718348;4319.8488052918;4320.330348795942;4321.524632290446;4327.8485253016;4665.646962380872;4886.364772579613;4893.804247830047;4905.23968784839;4923.335349727741;5619.662820230717;5679.801206957736;5684.6020778545935;5701.771929122789;5719.742611582489;1477.926103694811;1487.5314276003025;1492.2538730634683;1507.0806807847205;1509.8490150985033;928.6656803550717;963.6868017894212;968.162539403411;969.5394687523511;982.4694664881027;4941.751503066096;5026.39155470246;5036.445990940993;5037.974810125926;5048.343715317059;5119.771254898797;5150.021495915764;5188.818391356284;5219.5041471060185;5223.7910483580235;4980.676256043418;4985.401751789808;4995.077879440495;5236.507595335311;5605.579581531401;3091.5980922479935;3101.41073196092;3102.503599424081;3117.846396833186;3125.983740487373;6128.387161283925;6149.41580549847;6156.984097623842;6164.383561643889;6178.77564076932;5690.7439165237665;5873.823785286363;5927.288725352899;5930.466258036798;5967.8209653710455;6007.88854062;6019.60872543292;6041.24484439445;6077.17958075663;6159.5688301818445;5676.757680716011;5821.970890145522;5880.647161170344;5895.557833162444;5926.8738939601335;5809.9419005809405;5836.848102024859;5854.707264636836;5856.209411729362;5859.882802343844;5758.222639943721;5891.1752354671235;5991.910121348271;6034.155218269358;6131.417515336036;3918.863529576391;4250.893727656833;4299.097189590221;4303.419038429897;4317.309230523101;5876.118582212703;5903.468687818117;5942.138389933349;5951.970240148771;5952.196453478899;5494.615376923577;5802.448767367262;5806.709664516842;5807.912881306868;5813.418658134153;5712.772018912544;5730.197772311796;5750.827475175752;5761.913571296518;5764.146747360083;5704.486596206361;5712.600412898866;5716.742746576498;5776.295992682085;5822.446867547576;5660.801871934463;5753.626014309057;5762.4525670061275;5787.565932554991;5837.3870743571315;5608.523275521463;5616.410276920865;5618.050523475208;5634.352049514195;5686.516646085129 05:39:30 INFO - PROCESS | 3093 | |5;yui.html;823.016495288131;839.7690635075357;859.9183077607627;869.3914260017989;902.7607683963747;16591.83408165918;17607.119644017814;17655.646887062267;17669.7349539757;17675.11624418777;16169.272382742776;16222.918885405556;16382.426615068474;16392;16442;736.871047566675;760.3726925286661;766.3409467857638;769.1308821032226;871.1593312453481;887.4542156573725;898.1871406377239;906.2659246010719;906.8775715278452;907.4011152639259;970.403202030753;970.6214576315243;971.616211596419;971.8347880860235;972.6838777397339;767.7543186180425;962.4802606592432;965.7344230336644;965.8889227738795;965.8937516873139;664.8736740019381;686.4919959230184;691.6679993603099;701.6877489517167;702.388921638173;15694.764578531216;15864.52406427809;15882.523524294289;15890.84109158905;15911.60220994473;14995.850041499554;15121.546353609408;15715.842841571552;15848.76226856598;15943.44197953066;14327.641808954755;14376.71246575073;14390.208538530376;14560.053596516193;14649.633759156;4703.43558772946;4716.858494245178;4722.763861806896;4839.782209800567;4842.685225460334;84912.15087849104;85109.57445212734;85666.14333856644;86886.56556717175;92437.53781231119;11714.824277635842;12286.508539658493;12298.139130260795;12375.504979800888;12603.18079324841;24272.63591046136;24277.635835462483;24350.51298974046;24490.510189796103;25103;8707;8879.511626860585;8946.955265223633;9042.547872606343;9066.818663626691;5228.607854410934;5292.206169074592;5319.7340132993195;5376.91934620981;5390.622656414013;3776.66010059096;3892.82482288292;3932.115274063313;3934.1344904121047;3951.4270430787374;671.4796033074407;677.2786981864397;679.0832376292065;694.2051351202845;695.6347917343435;752.964673573836;795.4074214710078;809.3363441977522;830.2237408023517;835.7701632051113;9556.952215239018;9709.36889102206;10159.187265018782;10767.83848242277;10934.56261749537;626.0976553585506;667.6328019589206;759.3128218961848;786.697121608185;793.5437123653923;5193.324867767166;5195.68825870449;5218.6868787872845;5238.816641417532;5267.499587539178;70717;72782.63608682029;74222.62888685522;75077;75383.62308188425;111042.44478777554;113760;114059.42970285096;116752.41623791997;119800;61149.69425152935;101852;103045.48477257566;104252;106433.46783266189;9950.850737239092;9955.651552195639;10000.2999790014;10194.643187488698;10209;57580;57682.423175769385;58574.7071264658;59255.407445926714;60585.39414605797;48317.03365932591;49096.75451622719;55317.723411382685;79662.6016869912;82368.58815705882;12933.288669123289;12992.090553661154;13085;13190.80213796813;13326.666833329244;14995.475158369405;15005;15222.238888055332;15244.466443674419;16128.758068629215;13858.722825543231;14015.509457169357;14061.648458788612;14380.496682616058;14868;13149.408276627766;13181.275029873448;13728.39850282714;14225.786613201017;14369.784453233;13575.253361065235;13776.93111534476;13845;14041.227732474812;14044.227567483886;10940.781184376427;11147.832782508107;11171.888281117084;11421.942890285496;11592.710182245512;10392.532336044746;11469.139814513777;12293.200941938745;12429.564965226175;12522.624321270376 05:39:30 INFO - PROCESS | 3093 | __end_tp_report 05:39:30 INFO - PROCESS | 3093 | __start_cc_report 05:39:30 INFO - PROCESS | 3093 | _x_x_mozilla_cycle_collect,1152 05:39:30 INFO - PROCESS | 3093 | __end_cc_report 05:39:30 INFO - PROCESS | 3093 | __startTimestamp1473683970030__endTimestamp 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | ------- Summary: start ------- 05:39:30 INFO - PROCESS | 3093 | Number of tests: 6 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | [#0] dojo.html Cycles:165 Average:5603.43 Median:5827.97 stddev:626.06 (10.7%) stddev-sans-first:599.73 05:39:30 INFO - PROCESS | 3093 | Values: 3233.2 3236.7 3238.8 3280.3 3298.5 5517.4 5614.5 5615.3 5628.5 5650.9 5585.6 5635.4 5638.5 5644.3 5699.5 5051.6 5091.4 5106.3 5115.2 5171.7 5155.1 5225.4 5228.4 5234.2 5234.5 5054.4 5061.8 5089.6 5090.2 5130.3 5164.4 5186.2 5187.6 5188.7 5220.6 5015.4 5061.5 5088.7 5090.7 5100.8 5522.6 5526.7 5527.7 5543.6 5624.7 6027.9 6028.6 6037.4 6040.0 6043.8 5849.3 5939.4 5958.3 5969.7 6074.3 5579.5 5580.9 5597.7 5636.5 5645.5 6058.2 6114.9 6116.6 6117.0 6123.4 5784.9 5894.6 5931.9 5941.3 6012.8 6087.2 6088.1 6091.1 6092.6 6096.3 6014.6 6025.5 6028.2 6035.1 6040.6 5816.0 5855.6 6010.2 6034.8 6057.8 4652.5 4687.7 4689.0 4716.7 4724.1 4160.0 4168.2 4173.9 4180.6 4198.8 5563.4 5701.5 5727.7 5739.4 5864.5 6081.4 6083.5 6084.6 6090.4 6115.9 5773.6 5775.0 5787.4 5798.7 5802.2 5189.4 5199.4 5229.6 5246.4 5257.4 6304.7 6309.6 6311.3 6313.3 6313.7 6032.6 6089.1 6089.3 6093.3 6096.5 5996.9 6145.2 6177.0 6181.4 6319.9 5955.7 5955.8 5972.7 5973.2 5997.1 6041.7 6052.8 6058.3 6058.6 6060.9 5784.1 5796.0 5799.6 5842.5 5916.6 5819.4 5862.2 5864.0 5874.2 5892.9 5770.2 5828.0 5830.6 5831.2 5835.6 5776.9 5843.1 5852.8 5874.6 5890.2 5847.3 5856.2 5857.8 5877.2 5893.9 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | [#1] ext.html Cycles:180 Average:64107.26 Median:22874.66 stddev:125028.15 (546.6%) stddev-sans-first:125287.93 05:39:30 INFO - PROCESS | 3093 | Values: 588.6 640.8 664.1 665.5 666.4 34912.3 37894.8 37916.6 38285.2 38381.2 38162.8 47884.3 48642.3 48709.3 53126.7 9222.6 9350.5 9354.7 9363.3 9366.7 8841.3 9066.6 9201.5 9289.5 9296.6 8757.4 8802.7 11225.0 11477.0 11486.3 11467.7 11485.8 11532.4 11536.8 11548.9 8431.0 11156.0 11211.7 11258.8 11260.7 44443.0 45025.8 45233.3 49312.8 49366.5 3845.9 3905.2 4130.5 4325.9 4512.5 35195.8 35554.3 35830.8 36051.5 36056.3 4765.2 4781.7 4791.4 4801.6 4815.6 375889.1 376068.0 377118.0 377169.0 377294.0 59032.0 59290.4 59371.7 59417.0 59454.4 66790.7 67496.3 67726.3 68341.0 70737.3 18775.2 19017.8 19018.1 19033.0 19039.2 14078.0 14150.4 14176.0 14184.0 14188.0 17788.6 19067.2 19839.5 19971.6 20026.1 6980.5 7041.8 7075.2 7216.4 7424.6 1077.3 1144.7 1168.4 1170.2 1206.5 32725.8 34137.7 34738.8 35018.5 35233.5 1176.9 1188.7 1197.0 1221.6 1254.6 17997.0 18296.1 18367.4 18382.4 18484.0 618544.0 620489.0 621233.0 622082.0 625308.0 64025.0 64060.0 65133.0 65424.3 65635.0 1446.7 1448.5 1474.0 1481.3 1486.8 35089.6 36802.9 36843.8 36923.1 36967.4 57547.0 57846.0 57967.7 58179.0 58269.7 367740.0 369806.0 372556.0 374406.0 375100.0 45686.8 46193.1 46440.8 46668.8 47004.5 51756.7 52083.5 52946.0 53253.2 53710.2 43897.8 44061.6 45420.8 46675.0 46973.5 41618.8 44864.7 46698.0 47285.3 47954.3 45016.1 45886.0 46174.5 46318.5 46397.5 38158.6 39970.8 40550.8 40595.8 40724.2 30599.0 30611.8 30626.4 30682.8 31133.5 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | [#2] jquery.html Cycles:180 Average:30486.35 Median:24013.76 stddev:86648.56 (360.8%) stddev-sans-first:86862.17 05:39:30 INFO - PROCESS | 3093 | Values: 390.5 402.7 424.0 425.1 434.3 14763.7 19809.9 20197.4 20204.4 21585.4 20823.5 20833.7 20841.2 20846.9 21117.6 5823.6 5856.3 5987.3 6024.6 6026.9 5838.4 5847.5 5999.0 6007.0 6019.3 5880.7 5980.3 5994.4 5994.9 5995.1 5748.4 5920.9 5974.7 5978.4 5978.7 5335.4 5336.9 5344.5 5345.7 5345.9 23591.4 23742.4 23870.9 24156.6 24781.6 2497.9 2554.4 2598.9 2698.1 2992.1 14877.4 15081.5 15110.4 15491.9 15810.3 1974.4 1982.2 2034.7 2046.9 2091.2 50482.5 65179.0 85673.6 86277.0 88228.6 11307.8 11399.8 11403.3 11454.9 11611.1 15932.2 15970.8 15976.0 16005.3 16009.2 8397.3 8750.9 8793.4 8877.4 8939.0 8470.2 8477.0 8485.2 8681.5 8717.5 6899.3 6966.1 7135.2 7184.8 7185.6 1838.9 1931.4 2042.9 2099.5 2100.8 991.2 1036.1 1091.9 1094.3 1128.3 21063.9 21188.0 21312.7 21358.6 21361.3 1031.8 1087.1 1108.1 1115.6 1127.2 11589.7 12198.3 12245.8 12268.1 12348.2 520799.0 522276.0 525697.0 533752.0 535419.0 84982.0 87594.6 88117.6 92502.1 92809.4 1042.9 1043.5 1052.3 1055.2 1060.9 17568.6 18290.5 18493.2 18574.5 18898.4 41239.6 41539.2 42383.8 42960.8 46759.0 61234.7 62089.4 63513.0 63745.0 63945.0 7937.0 8301.6 8396.8 8754.4 9232.2 9902.4 11213.8 13861.2 14009.9 14053.4 11809.0 11888.4 11925.6 12166.6 12196.4 11587.8 11672.4 11766.4 11799.2 12499.8 11978.2 12083.8 12182.8 12194.2 12777.4 8178.1 8208.9 8296.2 8321.6 8621.8 10435.4 11705.8 11803.0 11863.3 12178.5 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | [#3] mootools.html Cycles:180 Average:6171.89 Median:4700.65 stddev:3501.68 (74.5%) stddev-sans-first:3493.98 05:39:30 INFO - PROCESS | 3093 | Values: 1510.2 1549.1 1707.3 1849.2 1860.1 8884.5 9585.4 9586.5 9602.9 9606.0 8245.9 9118.6 9283.8 9413.0 9432.6 2500.1 2533.4 2537.7 2538.2 2538.3 2382.7 2407.0 2407.7 2412.0 2416.5 2421.8 2435.7 2490.9 2494.6 2496.1 2375.1 2392.7 2393.0 2448.4 2450.9 2424.9 2441.8 2443.9 2446.2 2501.5 9831.7 9897.8 9908.6 9912.1 9938.5 5414.5 5500.9 5510.8 5515.2 5541.4 3221.2 3291.4 3344.6 3352.2 3371.4 1208.4 1212.0 1234.2 1282.3 1282.3 12623.9 12634.4 12644.7 12679.4 12699.2 4763.8 4834.2 4906.8 4998.1 5002.0 10574.8 10575.9 10600.3 10603.4 10609.6 5467.0 5471.9 5474.0 5475.0 5476.5 4637.5 4892.1 4966.0 5004.0 5117.2 4169.7 4232.6 4325.5 4332.7 4548.0 1345.0 1362.7 1368.1 1382.5 1383.1 1075.5 1086.9 1090.9 1091.4 1095.8 6329.2 6391.6 6580.2 6635.7 6857.1 493.6 504.1 507.5 512.0 514.8 4263.8 4279.5 4329.8 4363.6 4408.1 10791.7 11225.8 11318.7 11352.5 11355.8 10937.0 11274.2 11405.0 11421.1 11640.7 8543.0 8755.4 8764.0 8789.0 8830.6 6267.8 6574.2 6618.4 6691.2 6792.0 10515.3 10834.5 10848.5 10962.9 11048.7 11035.7 11047.1 11138.8 11143.5 11161.2 7334.9 8093.6 8295.2 8467.4 8782.1 9258.3 9267.9 9303.5 9318.0 9326.1 8039.8 8320.5 8323.0 8402.0 8618.7 7946.9 7964.5 8306.0 8320.9 8357.1 7346.9 7361.1 7574.4 7612.3 7637.0 5729.5 5812.0 5818.7 5823.8 6008.9 5720.7 5793.4 5842.6 5879.8 5918.5 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | [#4] prototype.html Cycles:180 Average:4788.64 Median:5400.04 stddev:1394.94 (25.8%) stddev-sans-first:1358.44 05:39:30 INFO - PROCESS | 3093 | Values: 347.6 354.5 356.6 357.1 359.1 5444.9 5456.3 5477.6 5480.7 5481.2 5387.8 5395.5 5404.6 5412.6 5423.0 3807.5 4086.6 4260.6 4274.9 4293.5 4287.0 4302.8 4305.8 4307.7 4337.7 4077.2 4250.3 4250.9 4273.8 4310.4 4204.2 4290.2 4295.7 4305.5 4323.2 3819.9 3829.8 3840.7 3846.7 3852.2 5190.3 5334.7 5342.3 5346.6 5355.4 4869.1 4882.6 4891.6 4908.0 4909.8 5660.8 5672.8 5688.3 5688.8 5746.9 3966.4 4149.8 4203.2 4204.1 4218.3 5582.5 5726.5 5879.3 5898.5 5902.6 4176.7 4185.5 4211.4 4268.1 4274.7 4295.9 4319.8 4320.3 4321.5 4327.8 4665.6 4886.4 4893.8 4905.2 4923.3 5619.7 5679.8 5684.6 5701.8 5719.7 1477.9 1487.5 1492.3 1507.1 1509.8 928.7 963.7 968.2 969.5 982.5 4941.8 5026.4 5036.4 5038.0 5048.3 5119.8 5150.0 5188.8 5219.5 5223.8 4980.7 4985.4 4995.1 5236.5 5605.6 3091.6 3101.4 3102.5 3117.8 3126.0 6128.4 6149.4 6157.0 6164.4 6178.8 5690.7 5873.8 5927.3 5930.5 5967.8 6007.9 6019.6 6041.2 6077.2 6159.6 5676.8 5822.0 5880.6 5895.6 5926.9 5809.9 5836.8 5854.7 5856.2 5859.9 5758.2 5891.2 5991.9 6034.2 6131.4 3918.9 4250.9 4299.1 4303.4 4317.3 5876.1 5903.5 5942.1 5952.0 5952.2 5494.6 5802.4 5806.7 5807.9 5813.4 5712.8 5730.2 5750.8 5761.9 5764.1 5704.5 5712.6 5716.7 5776.3 5822.4 5660.8 5753.6 5762.5 5787.6 5837.4 5608.5 5616.4 5618.1 5634.4 5686.5 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - PROCESS | 3093 | [#5] yui.html Cycles:180 Average:21061.07 Median:4710.15 stddev:29415.41 (624.5%) stddev-sans-first:29458.68 05:39:30 INFO - PROCESS | 3093 | Values: 823.0 839.8 859.9 869.4 902.8 16591.8 17607.1 17655.6 17669.7 17675.1 16169.3 16222.9 16382.4 16392.0 16442.0 736.9 760.4 766.3 769.1 871.2 887.5 898.2 906.3 906.9 907.4 970.4 970.6 971.6 971.8 972.7 767.8 962.5 965.7 965.9 965.9 664.9 686.5 691.7 701.7 702.4 15694.8 15864.5 15882.5 15890.8 15911.6 14995.9 15121.5 15715.8 15848.8 15943.4 14327.6 14376.7 14390.2 14560.1 14649.6 4703.4 4716.9 4722.8 4839.8 4842.7 84912.2 85109.6 85666.1 86886.6 92437.5 11714.8 12286.5 12298.1 12375.5 12603.2 24272.6 24277.6 24350.5 24490.5 25103.0 8707.0 8879.5 8947.0 9042.5 9066.8 5228.6 5292.2 5319.7 5376.9 5390.6 3776.7 3892.8 3932.1 3934.1 3951.4 671.5 677.3 679.1 694.2 695.6 753.0 795.4 809.3 830.2 835.8 9557.0 9709.4 10159.2 10767.8 10934.6 626.1 667.6 759.3 786.7 793.5 5193.3 5195.7 5218.7 5238.8 5267.5 70717.0 72782.6 74222.6 75077.0 75383.6 111042.4 113760.0 114059.4 116752.4 119800.0 61149.7 101852.0 103045.5 104252.0 106433.5 9950.9 9955.7 10000.3 10194.6 10209.0 57580.0 57682.4 58574.7 59255.4 60585.4 48317.0 49096.8 55317.7 79662.6 82368.6 12933.3 12992.1 13085.0 13190.8 13326.7 14995.5 15005.0 15222.2 15244.5 16128.8 13858.7 14015.5 14061.6 14380.5 14868.0 13149.4 13181.3 13728.4 14225.8 14369.8 13575.3 13776.9 13845.0 14041.2 14044.2 10940.8 11147.8 11171.9 11421.9 11592.7 10392.5 11469.1 12293.2 12429.6 12522.6 05:39:30 INFO - PROCESS | 3093 | -------- Summary: end -------- 05:39:30 INFO - PROCESS | 3093 | 05:39:30 INFO - TEST-INFO | 3093: exit 0 05:39:30 INFO - TEST-OK | dromaeo_css | took 1113712ms 05:39:30 INFO - TEST-START | kraken 05:39:30 INFO - operating with platform_type : linux_ 05:39:30 INFO - Initialising browser for kraken test... 05:39:30 INFO - TEST-INFO | started process 3293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLefVMA/profile http://localhost:33320/getInfo.html) 05:39:34 INFO - PROCESS | 3293 | __metrics Screen width/height:1600/1200 05:39:34 INFO - PROCESS | 3293 | colorDepth:24 05:39:34 INFO - PROCESS | 3293 | Browser inner width/height: 1024/697 05:39:34 INFO - PROCESS | 3293 | __metrics 05:39:35 INFO - PROCESS | 3293 | 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" 05:39:35 INFO - PROCESS | 3293 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:39:36 INFO - TEST-INFO | 3293: exit 0 05:39:36 INFO - Browser initialized. 05:39:36 INFO - Running cycle 1/1 for kraken test... 05:39:36 INFO - TEST-INFO | started process 3386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLefVMA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:39:48 INFO - PROCESS | 3386 | RSS: Main: 146530304 05:39:48 INFO - PROCESS | 3386 | 05:39:51 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33320/tests/kraken/driver.html?testName=audio-beat-detection) 05:39:51 INFO - PROCESS | 3386 | RSS: Main: 201277440 05:39:51 INFO - PROCESS | 3386 | 05:39:54 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33320/tests/kraken/driver.html?testName=audio-dft) 05:39:55 INFO - PROCESS | 3386 | RSS: Main: 185753600 05:39:55 INFO - PROCESS | 3386 | 05:39:57 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33320/tests/kraken/driver.html?testName=audio-fft) 05:39:58 INFO - PROCESS | 3386 | RSS: Main: 276766720 05:39:58 INFO - PROCESS | 3386 | 05:40:00 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33320/tests/kraken/driver.html?testName=audio-oscillator) 05:40:01 INFO - PROCESS | 3386 | RSS: Main: 181641216 05:40:01 INFO - PROCESS | 3386 | 05:40:03 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:03 INFO - PROCESS | 3386 | RSS: Main: 180813824 05:40:03 INFO - PROCESS | 3386 | 05:40:06 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33320/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:07 INFO - PROCESS | 3386 | RSS: Main: 216436736 05:40:07 INFO - PROCESS | 3386 | 05:40:10 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33320/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:11 INFO - PROCESS | 3386 | RSS: Main: 214925312 05:40:11 INFO - PROCESS | 3386 | 05:40:14 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33320/tests/kraken/driver.html?testName=json-parse-financial) 05:40:14 INFO - PROCESS | 3386 | RSS: Main: 215277568 05:40:14 INFO - PROCESS | 3386 | 05:40:16 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:16 INFO - PROCESS | 3386 | RSS: Main: 178208768 05:40:16 INFO - PROCESS | 3386 | 05:40:18 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:19 INFO - PROCESS | 3386 | RSS: Main: 184676352 05:40:19 INFO - PROCESS | 3386 | 05:40:21 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:21 INFO - PROCESS | 3386 | RSS: Main: 185954304 05:40:21 INFO - PROCESS | 3386 | 05:40:24 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:24 INFO - PROCESS | 3386 | RSS: Main: 182018048 05:40:24 INFO - PROCESS | 3386 | 05:40:27 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:28 INFO - PROCESS | 3386 | RSS: Main: 184758272 05:40:28 INFO - PROCESS | 3386 | 05:40:29 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:33320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33320/tests/kraken/driver.html?testName=ai-astar) 05:40:30 INFO - PROCESS | 3386 | RSS: Main: 236093440 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | __start_tp_report 05:40:30 INFO - PROCESS | 3386 | _x_x_mozilla_page_load 05:40:30 INFO - PROCESS | 3386 | _x_x_mozilla_page_load_details 05:40:30 INFO - PROCESS | 3386 | |i|pagename|runs| 05:40:30 INFO - PROCESS | 3386 | |0;ai-astar;114;111;119;110;111;111;110;112;112;111 05:40:30 INFO - PROCESS | 3386 | |1;audio-beat-detection;151;115;146;185;116;145;143;117;169;119 05:40:30 INFO - PROCESS | 3386 | |2;audio-dft;161;160;159;160;160;160;161;161;161;160 05:40:30 INFO - PROCESS | 3386 | |3;audio-fft;74;75;73;170;76;75;75;74;162;74 05:40:30 INFO - PROCESS | 3386 | |4;audio-oscillator;83;84;149;85;83;83;145;83;83;158 05:40:30 INFO - PROCESS | 3386 | |5;imaging-gaussian-blur;118;116;116;117;117;117;116;117;116;117 05:40:30 INFO - PROCESS | 3386 | |6;imaging-darkroom;146;146;145;146;146;145;146;145;146;146 05:40:30 INFO - PROCESS | 3386 | |7;imaging-desaturate;94;95;94;95;95;95;95;95;94;95 05:40:30 INFO - PROCESS | 3386 | |8;json-parse-financial;49;50;49;50;50;50;53;49;50;55 05:40:30 INFO - PROCESS | 3386 | |9;json-stringify-tinderbox;59;61;59;60;59;59;59;60;60;59 05:40:30 INFO - PROCESS | 3386 | |10;stanford-crypto-aes;72;72;72;72;77;77;73;71;77;77 05:40:30 INFO - PROCESS | 3386 | |11;stanford-crypto-ccm;119;128;123;116;115;117;127;125;161;126 05:40:30 INFO - PROCESS | 3386 | |12;stanford-crypto-pbkdf2;151;152;152;155;152;154;153;151;156;151 05:40:30 INFO - PROCESS | 3386 | |13;stanford-crypto-sha256-iterative;62;62;62;62;60;62;61;62;62;60 05:40:30 INFO - PROCESS | 3386 | __end_tp_report 05:40:30 INFO - PROCESS | 3386 | __start_cc_report 05:40:30 INFO - PROCESS | 3386 | _x_x_mozilla_cycle_collect,1687 05:40:30 INFO - PROCESS | 3386 | __end_cc_report 05:40:30 INFO - PROCESS | 3386 | __startTimestamp1473684030003__endTimestamp 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | ------- Summary: start ------- 05:40:30 INFO - PROCESS | 3386 | Number of tests: 14 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#0] ai-astar Cycles:10 Average:112.10 Median:111.50 stddev:2.69 (2.4%) stddev-sans-first:2.76 05:40:30 INFO - PROCESS | 3386 | Values: 114.0 111.0 119.0 110.0 111.0 111.0 110.0 112.0 112.0 111.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:145.50 stddev:24.09 (16.6%) stddev-sans-first:25.26 05:40:30 INFO - PROCESS | 3386 | Values: 151.0 115.0 146.0 185.0 116.0 145.0 143.0 117.0 169.0 119.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.67 (0.4%) stddev-sans-first:0.67 05:40:30 INFO - PROCESS | 3386 | Values: 161.0 160.0 159.0 160.0 160.0 160.0 161.0 161.0 161.0 160.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#3] audio-fft Cycles:10 Average:92.80 Median:74.50 stddev:38.63 (51.9%) stddev-sans-first:40.37 05:40:30 INFO - PROCESS | 3386 | Values: 74.0 75.0 73.0 170.0 76.0 75.0 75.0 74.0 162.0 74.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:83.00 stddev:32.64 (39.3%) stddev-sans-first:33.75 05:40:30 INFO - PROCESS | 3386 | Values: 83.0 84.0 149.0 85.0 83.0 83.0 145.0 83.0 83.0 158.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 05:40:30 INFO - PROCESS | 3386 | Values: 118.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 05:40:30 INFO - PROCESS | 3386 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 05:40:30 INFO - PROCESS | 3386 | Values: 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.00 stddev:1.96 (3.9%) stddev-sans-first:2.00 05:40:30 INFO - PROCESS | 3386 | Values: 49.0 50.0 49.0 50.0 50.0 50.0 53.0 49.0 50.0 55.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 05:40:30 INFO - PROCESS | 3386 | Values: 59.0 61.0 59.0 60.0 59.0 59.0 59.0 60.0 60.0 59.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:75.00 stddev:2.62 (3.5%) stddev-sans-first:2.68 05:40:30 INFO - PROCESS | 3386 | Values: 72.0 72.0 72.0 72.0 77.0 77.0 73.0 71.0 77.0 77.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:125.50 stddev:13.29 (10.6%) stddev-sans-first:13.88 05:40:30 INFO - PROCESS | 3386 | Values: 119.0 128.0 123.0 116.0 115.0 117.0 127.0 125.0 161.0 126.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:152.50 stddev:1.77 (1.2%) stddev-sans-first:1.76 05:40:30 INFO - PROCESS | 3386 | Values: 151.0 152.0 152.0 155.0 152.0 154.0 153.0 151.0 156.0 151.0 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - PROCESS | 3386 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:0.85 (1.4%) stddev-sans-first:0.88 05:40:30 INFO - PROCESS | 3386 | Values: 62.0 62.0 62.0 62.0 60.0 62.0 61.0 62.0 62.0 60.0 05:40:30 INFO - PROCESS | 3386 | -------- Summary: end -------- 05:40:30 INFO - PROCESS | 3386 | 05:40:30 INFO - TEST-INFO | 3386: exit 0 05:40:31 INFO - TEST-OK | kraken | took 60251ms 05:40:31 INFO - SUITE-END | took 1173s 05:40:31 INFO - Completed test suite (00:19:34) 05:40:31 INFO - javascript benchmark 05:40:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3233.240188555689, 3236.692514211149, 3238.8056716597007, 3280.294736631624, 3298.538204651349, 5517.420670829561, 5614.522765564931, 5615.2700148980575, 5628.493435590796, 5650.915236271459, 5585.58108141889, 5635.380108188096, 5638.520725738307, 5644.322681278242, 5699.458551437617, 5051.576687636034, 5091.439941606411, 5106.259592359106, 5115.181570948649, 5171.689698618095, 5155.149400348938, 5225.399079105913, 5228.398734145565, 5234.162533994561, 5234.476552344773, 5054.368203974504, 5061.79752809889, 5089.643724939256, 5090.211017292307, 5130.2817605535065, 5164.354455693038, 5186.247994040843, 5187.636865419422, 5188.662736922126, 5220.556252718505, 5015.3730783652045, 5061.468545802674, 5088.6946783193125, 5090.669106508065, 5100.821471248489, 5522.585806064561, 5526.696031718254, 5527.723613819333, 5543.584231182677, 5624.662520248758, 6027.939720602825, 6028.577999540034, 6037.426444487811, 6039.969800151015, 6043.788467403664, 5849.268841394825, 5939.406059394069, 5958.314793798725, 5969.731362088673, 6074.301455332605, 5579.525740312079, 5580.944190558123, 5597.692126933058, 5636.4927156556105, 5645.463680950304, 6058.1518587397795, 6114.877702445926, 6116.571839971249, 6116.969415152896, 6123.4488895999575, 5784.913226301609, 5894.557908156906, 5931.911021334683, 5941.316748573883, 6012.849678758066, 6087.178230938855, 6088.117223002729, 6091.116788065794, 6092.573519853657, 6096.29892562361, 6014.6391216527145, 6025.457708806229, 6028.216331876915, 6035.094735789579, 6040.607360521552, 5816.040353341651, 5855.560832937546, 6010.188624535719, 6034.758609655564, 6057.818265452043, 4652.534746525388, 4687.695299805502, 4689.038747056845, 4716.693414928019, 4724.078804633064, 4160.043190066302, 4168.228877657643, 4173.8956526087095, 4180.602842729936, 4198.811053502539, 5563.443655634404, 5701.486866182064, 5727.742251598688, 5739.368669446358, 5864.501517371071, 6081.391860813883, 6083.543734219951, 6084.6349219047, 6090.421409966135, 6115.938840611626, 5773.624714393551, 5774.971125144431, 5787.421257874179, 5798.681072540966, 5802.21670074543, 5189.4032186299055, 5199.428062913076, 5229.555487783505, 5246.396664383645, 5257.447967963385, 6304.7162877671535, 6309.589876658002, 6311.274203466568, 6313.305536390993, 6313.652749098752, 6032.577719559764, 6089.147519347383, 6089.33017368098, 6093.29927058394, 6096.512279017579, 5996.940030599638, 6145.231846019247, 6177.0, 6181.412765787243, 6319.9052014218905, 5955.702214889325, 5955.76176952917, 5972.671503067373, 5973.223480947449, 5997.070454079625, 6041.667708276087, 6052.848678783065, 6058.303295120942, 6058.606190597597, 6060.878782424239, 5784.1034639630925, 5796.014677504919, 5799.623024503394, 5842.503387212135, 5916.556258280732, 5819.359870414336, 5862.179294898803, 5863.970680146572, 5874.236349274567, 5892.852678683067, 5770.221020162392, 5827.9708601458415, 5830.62100963436, 5831.154482599914, 5835.620684655484, 5776.9133462998925, 5843.1235314703135, 5852.795152169653, 5874.559408044499, 5890.2342695449315, 5847.298324201123, 5856.150858125633, 5857.824265271878, 5877.23595932526, 5893.882122357443], "value": 5561.673338123894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.6409290332896, 640.836586670399, 664.126673424447, 665.5108495255989, 666.3602941176471, 34912.30175396494, 37894.81052594737, 37916.620833791654, 38285.23429531411, 38381.232375352476, 38162.8091859541, 47884.281735773904, 48642.27036594454, 48709.26936095962, 53126.73436632811, 9222.584983675733, 9350.485723285217, 9354.672586459475, 9363.34456588039, 9366.718998430058, 8841.292696584258, 9066.592003359865, 9201.539923003824, 9289.489078100702, 9296.628134874596, 8757.430767000125, 8802.691905783298, 11225.0, 11477.0, 11486.310821350704, 11467.713307167345, 11485.770284594344, 11532.365719885403, 11536.826947595793, 11548.942255288712, 8431.0, 11156.0, 11211.66365009051, 11258.774824503545, 11260.718482037933, 44443.0, 45025.77487112576, 45233.321500177524, 49312.75343623259, 49366.50633493655, 3845.8653947111716, 3905.1994341159993, 4130.524989626201, 4325.913481730379, 4512.481064677571, 35195.82402087999, 35554.28891422157, 35830.82084589586, 36051.4592281116, 36056.27887442263, 4765.237561990065, 4781.665283430161, 4791.448983366888, 4801.639877009238, 4815.614750819926, 375889.1205543982, 376068.0, 377118.0, 377169.0, 377294.0, 59032.0, 59290.40709592849, 59371.70314148402, 59417.0, 59454.40545594575, 66790.66604667043, 67496.32503674999, 67726.322736772, 68341.0, 70737.2926270741, 18775.15511801972, 19017.80982190188, 19018.144183511777, 19033.0, 19039.238430462625, 14078.0, 14150.43398264058, 14176.007679462546, 14184.007119501535, 14188.0, 17788.55528611795, 19067.237310507702, 19839.504012399804, 19971.60056798885, 20026.098825552886, 6980.546264492792, 7041.753538626123, 7075.150981882207, 7216.355740351194, 7424.552212318654, 1077.3374374759467, 1144.730988217768, 1168.3574034281112, 1170.2042611024415, 1206.541514224589, 32725.769663648465, 34137.65862341395, 34738.82630586831, 35018.474722879175, 35233.47149792755, 1176.8940795328463, 1188.6849984754047, 1196.9880301197052, 1221.6335099470123, 1254.6361555148906, 17997.0, 18296.085195740427, 18367.35714249995, 18382.356617518322, 18484.0, 618544.0, 620489.0, 621233.0, 622082.0, 625308.0, 64025.0, 64060.0, 65133.0, 65424.345756542774, 65635.0, 1446.674498237888, 1448.5147475595793, 1473.963150921214, 1481.282651595369, 1486.8067151270266, 35089.64910350864, 36802.86695653913, 36843.815780922, 36923.07692307714, 36967.44548831823, 57547.0, 57846.0, 57967.71016144892, 58179.0, 58269.70865145647, 367740.0, 369806.0, 372556.0, 374406.0, 375100.0, 45686.77156614329, 46193.07613847637, 46440.767796160806, 46668.7666561665, 47004.52995470002, 51756.741216293674, 52083.479165207864, 52946.0, 53253.201201982774, 53710.194347085606, 43897.78051109724, 44061.559384405744, 45420.772896135306, 46675.0, 46973.530264696914, 41618.791906041486, 44864.65147191826, 46698.0, 47285.29072063853, 47954.280685790436, 45016.09967800691, 45886.0, 46174.53825461702, 46318.53681463277, 46397.53602464067, 38158.61841381551, 39970.800145999085, 40550.79724601358, 40595.7970210147, 40724.17465388641, 30599.0, 30611.846940765154, 30626.387472250877, 30682.84658576693, 31133.53299700461], "value": 22154.101674820187, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.50600989747954, 402.65774092021786, 423.9830406783728, 425.109395815766, 434.2682579852948, 14763.704725905476, 19809.900950495226, 20197.39407817766, 20204.39386818396, 21585.352439426842, 20823.479413014684, 20833.68749468759, 20841.166353345885, 20846.89576552119, 21117.577648447023, 5823.563232757539, 5856.297244330686, 5987.28152621686, 6024.578279520435, 6026.9095963560585, 5838.4453476919625, 5847.532197424196, 5999.0, 6006.969965150168, 6019.337872833985, 5880.7353669085, 5980.342162362137, 5994.43052909975, 5994.940050599482, 5995.070764031582, 5748.425157484218, 5920.851978700523, 5974.671393073401, 5978.40215978403, 5978.671173085479, 5335.439778823206, 5336.866578335573, 5344.545713614348, 5345.679259244458, 5345.893082138375, 23591.410214744596, 23742.40643983897, 23870.880645596662, 24156.63765043526, 24781.62827557588, 2497.862617556034, 2554.4380236347974, 2598.944459409571, 2698.1096238241335, 2992.1173253890156, 14877.404903803836, 15081.54755357341, 15110.39558417662, 15491.922540387339, 15810.288537015862, 1974.4076776966851, 1982.2368388170514, 2034.674452087659, 2046.8771873687479, 2091.215794077221, 50482.49517504815, 65179.0, 85673.57163214144, 86277.0, 88228.55885720531, 11307.77728347417, 11399.829002564968, 11403.258788178742, 11454.885451145548, 11611.129165312635, 15932.167800702835, 15970.76043859343, 15976.0, 16005.279762410719, 16009.199540022952, 8397.328213742885, 8750.912490875136, 8793.428427152214, 8877.422967507091, 8938.955305223431, 8470.195331443503, 8476.957615211884, 8485.193906578865, 8681.479111253218, 8717.52053637056, 6899.275576064541, 6966.12923384577, 7135.215126336099, 7184.820379490555, 7185.568865868065, 1838.945125997173, 1931.381957773499, 2042.9182832686824, 2099.4751312171957, 2100.821430178452, 991.2393982757984, 1036.1451802263164, 1091.9071878890384, 1094.261373572835, 1128.3230061962756, 21063.8946805265, 21188.04653790583, 21312.680309795367, 21358.572828543343, 21361.25235616746, 1031.7936412717486, 1087.1085709718113, 1108.0969010256615, 1115.5649296774266, 1127.1602656021316, 11589.6523104307, 12198.32909190003, 12245.755084898252, 12268.141230113984, 12348.197367171106, 520799.0, 522276.0, 525697.0, 533752.0, 535419.0, 84982.0, 87594.56202718946, 88117.55941220382, 92502.0749792507, 92809.35953202313, 1042.9061384475433, 1043.4730461117088, 1052.310736467615, 1055.2402270365324, 1060.9257351985286, 17568.648627027644, 18290.542736431697, 18493.167807448965, 18574.535636609195, 18898.433047008613, 41239.587604124776, 41539.1692166161, 42383.7880810594, 42960.78519607382, 46759.0, 61234.69382653236, 62089.37910620836, 63513.0, 63745.0, 63945.0, 7937.0, 8301.58492075406, 8396.832063358821, 8754.387192896436, 9232.169104780602, 9902.405855648683, 11213.775724485628, 13861.23763193034, 14009.859901400856, 14053.36759845789, 11809.0, 11888.405579721153, 11925.642230732745, 12166.635000949986, 12196.390180491117, 11587.8261826071, 11672.416379180837, 11766.411679415824, 11799.233049851731, 12499.812502812283, 11978.161528692908, 12083.818742719042, 12182.817257741319, 12194.207376520853, 12777.361131943551, 8178.059523154913, 8208.917910820815, 8296.170382961656, 8321.625526851427, 8621.82756344857, 10435.426051567236, 11705.765884682089, 11803.0, 11863.34751588671, 12178.512859485521], "value": 9771.475538829192, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1510.1995942150656, 1549.1402271739184, 1707.2658756734606, 1849.2418108575487, 1860.051373799362, 8884.511351875644, 9585.424874507517, 9586.472743999077, 9602.855957160647, 9606.0393960604, 8245.876311855327, 9118.635254589808, 9283.767905802359, 9413.0, 9432.622695092205, 2500.074972260262, 2533.366658335416, 2537.695476542808, 2538.1877799104295, 2538.2512158913073, 2382.702162229721, 2407.0492155598527, 2407.6870006899167, 2412.0110754590623, 2416.5046165536023, 2421.83047186697, 2435.7198922123916, 2490.8754562271906, 2494.6008638617823, 2496.10140349474, 2375.0618505690327, 2392.6530653055297, 2393.0, 2448.424620214248, 2450.8529488230765, 2424.91512797053, 2441.8046556275453, 2443.914462993787, 2446.2294377271282, 2501.474690315018, 9831.705048848475, 9897.802043959153, 9908.603655853758, 9912.058354456316, 9938.503074846229, 5414.539764120054, 5500.862478438031, 5510.8071217507595, 5515.227868098429, 5541.445855414467, 3221.194701324669, 3291.416238252984, 3344.6488118747425, 3352.178716214543, 3371.359441706068, 1208.383724300613, 1211.9878801211962, 1234.2471092633575, 1282.3011458754909, 1282.3203702037936, 12623.936880315632, 12634.431450584745, 12644.747105057848, 12679.429425675775, 12699.174553653984, 4763.809447622057, 4834.226523756182, 4906.773306673332, 4998.050370429607, 5001.974990125026, 10574.788504229873, 10575.947120264349, 10600.310979786289, 10603.416812075257, 10609.57561697539, 5466.972665136649, 5471.945280547223, 5474.042042642522, 5474.972625136849, 5476.479734425223, 4637.489876113625, 4892.143874821892, 4966.0, 5004.0, 5117.2068329409, 4169.666426685858, 4232.640225580799, 4325.52419233884, 4332.740035597874, 4548.044910568818, 1344.9596512104652, 1362.693393986365, 1368.1107280267906, 1382.536850155189, 1383.0871624727833, 1075.499892549968, 1086.9239153259355, 1090.8963648453384, 1091.4433638844089, 1095.7534554725123, 6329.177206963157, 6391.58454700443, 6580.177477815273, 6635.668216589152, 6857.142857142857, 493.5705935835846, 504.1328914267454, 507.5077175140182, 511.98464046078675, 514.8275327765166, 4263.76549289792, 4279.486461624562, 4329.848455304049, 4363.629091527192, 4408.096340250241, 10791.730206744895, 11225.831612525819, 11318.660440186808, 11352.545898164146, 11355.772884542263, 10937.0, 11274.210805243718, 11405.0, 11421.1434142438, 11640.650780476599, 8543.0, 8755.387122901464, 8764.0, 8789.0, 8830.64677412909, 6267.780627678009, 6574.211094668671, 6618.371254730793, 6691.230508491586, 6792.0, 10515.316504427188, 10834.458277086273, 10848.45757712127, 10962.945185274022, 11048.668539943814, 11035.668929932115, 11047.116230701686, 11138.832917506083, 11143.498542565441, 11161.218714689894, 7334.853302934018, 8093.55485448306, 8295.211954864279, 8467.364947629076, 8782.077881822317, 9258.305627077862, 9267.907320926704, 9303.488308143116, 9317.953410232905, 9326.067393260892, 8039.799005024921, 8320.542370169698, 8322.958385208036, 8402.0, 8618.655253789779, 7946.92053079485, 7964.482308649919, 8306.0, 8320.916790832014, 8357.122502137432, 7346.926530734624, 7361.079865016873, 7574.431917606092, 7612.276833700789, 7636.961815190888, 5729.512991395778, 5812.0, 5818.650880947157, 5823.79616713413, 6008.939910600838, 5720.713964301852, 5793.391693872083, 5842.561807864342, 5879.794207202727, 5918.496927761188], "value": 4868.691814061684, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.62109300862056, 354.46652787554734, 356.5756749468134, 357.0983267250193, 359.08075327162464, 5444.863878403042, 5456.3358175756375, 5477.561795056396, 5480.698561579122, 5481.177823326503, 5387.78448862046, 5395.460453954603, 5404.621676482648, 5412.564346044276, 5422.9728851355685, 3807.4859893914245, 4086.6117718816663, 4260.552641972595, 4274.893127671818, 4293.463317085364, 4286.97856510717, 4302.806373713175, 4305.763183024932, 4307.655387569002, 4337.652987760972, 4077.2049450357194, 4250.277452833026, 4250.936235956463, 4273.786310684469, 4310.374995625632, 4204.2432362174795, 4290.184864875664, 4295.656347492193, 4305.461817272841, 4323.2434323993175, 3819.9181446111934, 3829.8468061277517, 3840.7311488195837, 3846.7114966377635, 3852.248811481762, 5190.313148226842, 5334.653247538937, 5342.27879236302, 5346.599005074635, 5355.357357117132, 4869.099216644897, 4882.609391248727, 4891.584215341701, 4908.0, 4909.754512274408, 5660.830175094754, 5672.8014519492035, 5688.345840228385, 5688.772449102031, 5746.856328591819, 3966.365381538969, 4149.751014939114, 4203.159368126387, 4204.066129580397, 4218.346156345771, 5582.506082603502, 5726.513246374063, 5879.265091863517, 5898.528117750612, 5902.61632993854, 4176.7076304658385, 4185.497740271126, 4211.410402543646, 4268.125034367947, 4274.722143060691, 4295.914081718348, 4319.8488052918, 4320.330348795942, 4321.524632290446, 4327.8485253016, 4665.646962380872, 4886.364772579613, 4893.804247830047, 4905.23968784839, 4923.335349727741, 5619.662820230717, 5679.801206957736, 5684.6020778545935, 5701.771929122789, 5719.742611582489, 1477.926103694811, 1487.5314276003025, 1492.2538730634683, 1507.0806807847205, 1509.8490150985033, 928.6656803550717, 963.6868017894212, 968.162539403411, 969.5394687523511, 982.4694664881027, 4941.751503066096, 5026.39155470246, 5036.445990940993, 5037.974810125926, 5048.343715317059, 5119.771254898797, 5150.021495915764, 5188.818391356284, 5219.5041471060185, 5223.7910483580235, 4980.676256043418, 4985.401751789808, 4995.077879440495, 5236.507595335311, 5605.579581531401, 3091.5980922479935, 3101.41073196092, 3102.503599424081, 3117.846396833186, 3125.983740487373, 6128.387161283925, 6149.41580549847, 6156.984097623842, 6164.383561643889, 6178.77564076932, 5690.7439165237665, 5873.823785286363, 5927.288725352899, 5930.466258036798, 5967.8209653710455, 6007.88854062, 6019.60872543292, 6041.24484439445, 6077.17958075663, 6159.5688301818445, 5676.757680716011, 5821.970890145522, 5880.647161170344, 5895.557833162444, 5926.8738939601335, 5809.9419005809405, 5836.848102024859, 5854.707264636836, 5856.209411729362, 5859.882802343844, 5758.222639943721, 5891.1752354671235, 5991.910121348271, 6034.155218269358, 6131.417515336036, 3918.863529576391, 4250.893727656833, 4299.097189590221, 4303.419038429897, 4317.309230523101, 5876.118582212703, 5903.468687818117, 5942.138389933349, 5951.970240148771, 5952.196453478899, 5494.615376923577, 5802.448767367262, 5806.709664516842, 5807.912881306868, 5813.418658134153, 5712.772018912544, 5730.197772311796, 5750.827475175752, 5761.913571296518, 5764.146747360083, 5704.486596206361, 5712.600412898866, 5716.742746576498, 5776.295992682085, 5822.446867547576, 5660.801871934463, 5753.626014309057, 5762.4525670061275, 5787.565932554991, 5837.3870743571315, 5608.523275521463, 5616.410276920865, 5618.050523475208, 5634.352049514195, 5686.516646085129], "value": 4349.427801432305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.016495288131, 839.7690635075357, 859.9183077607627, 869.3914260017989, 902.7607683963747, 16591.83408165918, 17607.119644017814, 17655.646887062267, 17669.7349539757, 17675.11624418777, 16169.272382742776, 16222.918885405556, 16382.426615068474, 16392.0, 16442.0, 736.871047566675, 760.3726925286661, 766.3409467857638, 769.1308821032226, 871.1593312453481, 887.4542156573725, 898.1871406377239, 906.2659246010719, 906.8775715278452, 907.4011152639259, 970.403202030753, 970.6214576315243, 971.616211596419, 971.8347880860235, 972.6838777397339, 767.7543186180425, 962.4802606592432, 965.7344230336644, 965.8889227738795, 965.8937516873139, 664.8736740019381, 686.4919959230184, 691.6679993603099, 701.6877489517167, 702.388921638173, 15694.764578531216, 15864.52406427809, 15882.523524294289, 15890.84109158905, 15911.60220994473, 14995.850041499554, 15121.546353609408, 15715.842841571552, 15848.76226856598, 15943.44197953066, 14327.641808954755, 14376.71246575073, 14390.208538530376, 14560.053596516193, 14649.633759156, 4703.43558772946, 4716.858494245178, 4722.763861806896, 4839.782209800567, 4842.685225460334, 84912.15087849104, 85109.57445212734, 85666.14333856644, 86886.56556717175, 92437.53781231119, 11714.824277635842, 12286.508539658493, 12298.139130260795, 12375.504979800888, 12603.18079324841, 24272.63591046136, 24277.635835462483, 24350.51298974046, 24490.510189796103, 25103.0, 8707.0, 8879.511626860585, 8946.955265223633, 9042.547872606343, 9066.818663626691, 5228.607854410934, 5292.206169074592, 5319.7340132993195, 5376.91934620981, 5390.622656414013, 3776.66010059096, 3892.82482288292, 3932.115274063313, 3934.1344904121047, 3951.4270430787374, 671.4796033074407, 677.2786981864397, 679.0832376292065, 694.2051351202845, 695.6347917343435, 752.964673573836, 795.4074214710078, 809.3363441977522, 830.2237408023517, 835.7701632051113, 9556.952215239018, 9709.36889102206, 10159.187265018782, 10767.83848242277, 10934.56261749537, 626.0976553585506, 667.6328019589206, 759.3128218961848, 786.697121608185, 793.5437123653923, 5193.324867767166, 5195.68825870449, 5218.6868787872845, 5238.816641417532, 5267.499587539178, 70717.0, 72782.63608682029, 74222.62888685522, 75077.0, 75383.62308188425, 111042.44478777554, 113760.0, 114059.42970285096, 116752.41623791997, 119800.0, 61149.69425152935, 101852.0, 103045.48477257566, 104252.0, 106433.46783266189, 9950.850737239092, 9955.651552195639, 10000.2999790014, 10194.643187488698, 10209.0, 57580.0, 57682.423175769385, 58574.7071264658, 59255.407445926714, 60585.39414605797, 48317.03365932591, 49096.75451622719, 55317.723411382685, 79662.6016869912, 82368.58815705882, 12933.288669123289, 12992.090553661154, 13085.0, 13190.80213796813, 13326.666833329244, 14995.475158369405, 15005.0, 15222.238888055332, 15244.466443674419, 16128.758068629215, 13858.722825543231, 14015.509457169357, 14061.648458788612, 14380.496682616058, 14868.0, 13149.408276627766, 13181.275029873448, 13728.39850282714, 14225.786613201017, 14369.784453233, 13575.253361065235, 13776.93111534476, 13845.0, 14041.227732474812, 14044.227567483886, 10940.781184376427, 11147.832782508107, 11171.888281117084, 11421.942890285496, 11592.710182245512, 10392.532336044746, 11469.139814513777, 12293.200941938745, 12429.564965226175, 12522.624321270376], "value": 8052.01590822366, "unit": "score"}], "name": "dromaeo_css", "value": 7680.729015978299, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 119.0, 110.0, 111.0, 111.0, 110.0, 112.0, 112.0, 111.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 146.0, 185.0, 116.0, 145.0, 143.0, 117.0, 169.0, 119.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 160.0, 160.0, 160.0, 161.0, 161.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 170.0, 76.0, 75.0, 75.0, 74.0, 162.0, 74.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 149.0, 85.0, 83.0, 83.0, 145.0, 83.0, 83.0, 158.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 50.0, 50.0, 53.0, 49.0, 50.0, 55.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 72.0, 77.0, 77.0, 73.0, 71.0, 77.0, 77.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 128.0, 123.0, 116.0, 115.0, 117.0, 127.0, 125.0, 161.0, 126.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 152.0, 155.0, 152.0, 154.0, 153.0, 151.0, 156.0, 151.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 60.0, 62.0, 61.0, 62.0, 62.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1490.4, "alertThreshold": 2.0}]} 05:40:31 INFO - Return code: 0 05:40:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:31 INFO - # TBPL SUCCESS # 05:40:31 INFO - Running post-action listener: _resource_record_post_action 05:40:31 INFO - [mozharness: 2016-09-12 12:40:31.284931Z] Finished run-tests step (success) 05:40:31 INFO - Running post-run listener: _resource_record_post_run 05:40:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328081408; Read time: 28; Write time: 366088 05:40:31 INFO - TinderboxPrint: CPU usage
12.9% 05:40:31 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 05:40:31 INFO - TinderboxPrint: I/O write bytes / time
328,081,408 / 366,088 05:40:31 INFO - TinderboxPrint: CPU idle
8,268.4 (87.1%) 05:40:31 INFO - TinderboxPrint: CPU user
1,183.0 (12.5%) 05:40:31 INFO - TinderboxPrint: Swap in / out
0 / 0 05:40:31 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78495744; Read time: 0; Write time: 139288 05:40:31 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249159680; Read time: 12; Write time: 226756 05:40:31 INFO - Running post-run listener: _upload_blobber_files 05:40:31 INFO - Blob upload gear active. 05:40:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:31 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 05:40:31 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'] 05:40:31 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 05:40:32 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:35 INFO - (blobuploader) - INFO - Done attempting. 05:40:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:40:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:40:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:36 INFO - (blobuploader) - INFO - Done attempting. 05:40:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:40:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:40:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:37 INFO - (blobuploader) - INFO - Done attempting. 05:40:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:37 INFO - Return code: 0 05:40:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a787144d4d9c72917009b09b288a12d7e6c51adab84781598a346af1f98173f9fbe5b47bb68028c18dc4c4c873ac9573b2b78f5d9e7c78478e2659c9b4dbd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d32796f56720b94dad19a8e1976c56d22c0b9dd258adbaa22ea786779847f184b53c4d658fb8dee9403dfe657cbb8eb7fbe4bd50571c336e1e0a170bd2b0c383", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5800f07f38f41a4a4dc2c559b6853e7d233e12834f20bb46700f9517fc0c73b9858e62c42dc1baa48af9cf649f20caf987609943470ff8ec37bdd453918fe085"} 05:40:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:37 INFO - Contents: 05:40:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a787144d4d9c72917009b09b288a12d7e6c51adab84781598a346af1f98173f9fbe5b47bb68028c18dc4c4c873ac9573b2b78f5d9e7c78478e2659c9b4dbd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d32796f56720b94dad19a8e1976c56d22c0b9dd258adbaa22ea786779847f184b53c4d658fb8dee9403dfe657cbb8eb7fbe4bd50571c336e1e0a170bd2b0c383", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5800f07f38f41a4a4dc2c559b6853e7d233e12834f20bb46700f9517fc0c73b9858e62c42dc1baa48af9cf649f20caf987609943470ff8ec37bdd453918fe085"} 05:40:37 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:37 INFO - Copying logs to upload dir... 05:40:37 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.634147 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-12 05:40:36.694716) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:36.698376) ========= 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=af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800 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/3a787144d4d9c72917009b09b288a12d7e6c51adab84781598a346af1f98173f9fbe5b47bb68028c18dc4c4c873ac9573b2b78f5d9e7c78478e2659c9b4dbd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d32796f56720b94dad19a8e1976c56d22c0b9dd258adbaa22ea786779847f184b53c4d658fb8dee9403dfe657cbb8eb7fbe4bd50571c336e1e0a170bd2b0c383", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5800f07f38f41a4a4dc2c559b6853e7d233e12834f20bb46700f9517fc0c73b9858e62c42dc1baa48af9cf649f20caf987609943470ff8ec37bdd453918fe085"} build_url:https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/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/3a787144d4d9c72917009b09b288a12d7e6c51adab84781598a346af1f98173f9fbe5b47bb68028c18dc4c4c873ac9573b2b78f5d9e7c78478e2659c9b4dbd87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d32796f56720b94dad19a8e1976c56d22c0b9dd258adbaa22ea786779847f184b53c4d658fb8dee9403dfe657cbb8eb7fbe4bd50571c336e1e0a170bd2b0c383", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5800f07f38f41a4a4dc2c559b6853e7d233e12834f20bb46700f9517fc0c73b9858e62c42dc1baa48af9cf649f20caf987609943470ff8ec37bdd453918fe085"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:36.723600) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:36.723925) ========= 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=af724c981e2a0f424dbabf99000001e7-1473681910.222621-895901800 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:36.806889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:36.807247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:36.807564) ========= ========= Total master_lag: 0.21 =========