builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-037 starttime: 1473682377.62 results: success (0) buildid: 20160910055116 builduid: fb98bf9a800847589b1767e37e53bef3 revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.617201) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.617704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.618006) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.649260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.649519) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 05:12:57-- 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% 226M=0s 2016-09-12 05:12:58 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102992 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.783007) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.783306) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021004 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.851736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:57.852078) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 05:12:58,190 truncating revision to first 12 chars 2016-09-12 05:12:58,190 Setting DEBUG logging. 2016-09-12 05:12:58,190 attempt 1/10 2016-09-12 05:12:58,190 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:58,489 unpacking tar archive at: mozilla-aurora-8663d41e015f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.626583 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.518260) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.518562) ========= script_repo_revision: 8663d41e015f3c9933a08a65c8349b86f2263354 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.518977) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.519249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 05:12:58.527979) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-12 05:12:58.528446) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:12:58 INFO - MultiFileLogger online at 20160912 05:12:58 in /builds/slave/test 05:12:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:12:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:58 INFO - {'append_to_log': False, 05:12:58 INFO - 'base_work_dir': '/builds/slave/test', 05:12:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:12:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:58 INFO - 'branch': 'Mozilla-Aurora', 05:12:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:58 INFO - 'config_files': ('talos/linux_config.py',), 05:12:58 INFO - 'default_actions': ('clobber', 05:12:58 INFO - 'read-buildbot-config', 05:12:58 INFO - 'download-and-extract', 05:12:58 INFO - 'populate-webroot', 05:12:58 INFO - 'create-virtualenv', 05:12:58 INFO - 'install', 05:12:58 INFO - 'run-tests'), 05:12:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:58 INFO - 'download_minidump_stackwalk': True, 05:12:58 INFO - 'download_symbols': 'ondemand', 05:12:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:58 INFO - 'installer_path': 'installer.exe', 05:12:58 INFO - 'log_level': 'info', 05:12:58 INFO - 'log_name': 'talos', 05:12:58 INFO - 'log_to_console': True, 05:12:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:12:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:12:58 INFO - 'opt_config_files': (), 05:12:58 INFO - 'pip_index': False, 05:12:58 INFO - 'sps_profile': False, 05:12:58 INFO - 'sps_profile_interval': 0, 05:12:58 INFO - 'suite': 'dromaeojs-e10s', 05:12:58 INFO - 'system_bits': '32', 05:12:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:58 INFO - 'title': 'talos-linux64-ix-037', 05:12:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:58 INFO - 'use_talos_json': True, 05:12:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:58 INFO - 'work_dir': 'build'} 05:12:58 INFO - [mozharness: 2016-09-12 12:12:58.934714Z] Running clobber step. 05:12:58 INFO - Running pre-action listener: _resource_record_pre_action 05:12:58 INFO - Running main action method: clobber 05:12:58 INFO - rmtree: /builds/slave/test/build 05:12:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:59 INFO - Running post-action listener: _resource_record_post_action 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.385005Z] Finished clobber step (success) 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.385105Z] Running read-buildbot-config step. 05:12:59 INFO - Running pre-action listener: _resource_record_pre_action 05:12:59 INFO - Running main action method: read_buildbot_config 05:12:59 INFO - Using buildbot properties: 05:12:59 INFO - { 05:12:59 INFO - "project": "", 05:12:59 INFO - "product": "firefox", 05:12:59 INFO - "script_repo_revision": "production", 05:12:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 05:12:59 INFO - "repository": "", 05:12:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 05:12:59 INFO - "buildid": "20160910055116", 05:12:59 INFO - "pgo_build": "True", 05:12:59 INFO - "basedir": "/builds/slave/test", 05:12:59 INFO - "buildnumber": 549, 05:12:59 INFO - "slavename": "talos-linux64-ix-037", 05:12:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:12:59 INFO - "platform": "ubuntu64_hw", 05:12:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:12:59 INFO - "branch": "mozilla-aurora", 05:12:59 INFO - "repo_path": "releases/mozilla-aurora", 05:12:59 INFO - "revision": "8663d41e015f3c9933a08a65c8349b86f2263354", 05:12:59 INFO - "stage_platform": "linux64-pgo", 05:12:59 INFO - "builduid": "fb98bf9a800847589b1767e37e53bef3", 05:12:59 INFO - "slavebuilddir": "test" 05:12:59 INFO - } 05:12:59 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:59 INFO - Running post-action listener: _resource_record_post_action 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.387802Z] Finished read-buildbot-config step (success) 05:12:59 INFO - [mozharness: 2016-09-12 12:12:59.387891Z] Running download-and-extract step. 05:12:59 INFO - Running pre-action listener: _resource_record_pre_action 05:12:59 INFO - Running main action method: download_and_extract 05:12:59 INFO - mkdir: /builds/slave/test/build/tests 05:12:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:12:59 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:59 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:59 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:59 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:59 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:59 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:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:12:59 INFO - retry: Failed, sleeping 30 seconds before retrying 05:13:29 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:29 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:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:13:29 INFO - retry: Failed, sleeping 60 seconds before retrying 05:14:29 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:29 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:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:14:29 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:29 INFO - Caught exception: HTTP Error 404: Not Found 05:14:29 INFO - Caught exception: HTTP Error 404: Not Found 05:14:29 INFO - Caught exception: HTTP Error 404: Not Found 05:14:29 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:29 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:29 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:30 INFO - Downloaded 1361 bytes. 05:14:30 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 05:14:30 INFO - Using the following test package requirements: 05:14:30 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:30 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:14:30 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:14:30 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'jsshell-linux-x86_64.zip'], 05:14:30 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:14:30 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 05:14:30 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:14:30 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:14:30 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:14:30 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 05:14:30 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:14:30 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:14:30 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:30 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:30 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:30 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:30 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:30 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:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:14:30 INFO - retry: Failed, sleeping 30 seconds before retrying 05:15:00 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:15:00 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:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:15:00 INFO - retry: Failed, sleeping 60 seconds before retrying 05:16:00 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:16:00 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:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:16:00 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:16:00 INFO - Caught exception: HTTP Error 404: Not Found 05:16:00 INFO - Caught exception: HTTP Error 404: Not Found 05:16:00 INFO - Caught exception: HTTP Error 404: Not Found 05:16:00 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:16:00 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:16:00 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:16:01 INFO - Downloaded 21770904 bytes. 05:16:01 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:16:01 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:16:02 INFO - Return code: 0 05:16:02 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:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:02 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:16:02 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:16:02 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:16:02 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:16:02 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:16:02 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:02 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:16:02 INFO - retry: Failed, sleeping 30 seconds before retrying 05:16:32 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:32 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:32 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:16:32 INFO - retry: Failed, sleeping 60 seconds before retrying 05:17:32 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:32 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:32 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:17:32 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:32 INFO - Caught exception: HTTP Error 404: Not Found 05:17:32 INFO - Caught exception: HTTP Error 404: Not Found 05:17:32 INFO - Caught exception: HTTP Error 404: Not Found 05:17:32 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:32 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:32 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:33 INFO - Downloaded 21770904 bytes. 05:17:33 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:33 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:34 INFO - Return code: 0 05:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:34 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:35 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:35 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:35 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:35 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:35 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:35 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:17:35 INFO - retry: Failed, sleeping 30 seconds before retrying 05:18:05 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:18:05 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:05 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:18:05 INFO - retry: Failed, sleeping 60 seconds before retrying 05:19:05 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:19:05 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:19:05 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:19:05 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:19:05 INFO - Caught exception: HTTP Error 404: Not Found 05:19:05 INFO - Caught exception: HTTP Error 404: Not Found 05:19:05 INFO - Caught exception: HTTP Error 404: Not Found 05:19:05 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:19:05 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:19:05 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:19:05 INFO - Downloaded 14413740 bytes. 05:19:05 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:19:05 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:19:06 INFO - Return code: 0 05:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:06 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:19:06 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:19:06 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:19:06 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:19:06 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:19:06 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:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 05:19:06 INFO - retry: Failed, sleeping 30 seconds before retrying 05:19:36 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:36 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:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 05:19:36 INFO - retry: Failed, sleeping 60 seconds before retrying 05:20:36 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:36 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:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 05:20:36 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:36 INFO - Caught exception: HTTP Error 404: Not Found 05:20:36 INFO - Caught exception: HTTP Error 404: Not Found 05:20:36 INFO - Caught exception: HTTP Error 404: Not Found 05:20:36 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:36 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:36 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:37 INFO - Downloaded 63895992 bytes. 05:20:37 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:37 INFO - mkdir: /builds/slave/test/properties 05:20:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:20:37 INFO - Writing to file /builds/slave/test/properties/build_url 05:20:37 INFO - Contents: 05:20:37 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:38 INFO - Running post-action listener: _resource_record_post_action 05:20:38 INFO - Running post-action listener: set_extra_try_arguments 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.141687Z] Finished download-and-extract step (success) 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.141920Z] Running populate-webroot step. 05:20:38 INFO - Running pre-action listener: _resource_record_pre_action 05:20:38 INFO - Running main action method: populate_webroot 05:20:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:20:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:20:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:20:38 INFO - '--tppagecycles', 05:20:38 INFO - '7'], 05:20:38 INFO - 'tests': ['tsvgm']}}, 05:20:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:20:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:20:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:20:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:20:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:20:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['damp', 'tps']}, 05:20:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'tests': ['damp', 'tps']}, 05:20:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['dromaeo_dom']}, 05:20:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:20:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['basic_compositor_video']}, 05:20:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:20:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other-e10s': {'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['a11yr', 05:20:38 INFO - 'ts_paint', 05:20:38 INFO - 'tpaint', 05:20:38 INFO - 'sessionrestore', 05:20:38 INFO - 'sessionrestore_no_auto_restore', 05:20:38 INFO - 'tabpaint']}, 05:20:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tsvgx', 05:20:38 INFO - 'tsvgr_opacity', 05:20:38 INFO - 'tart', 05:20:38 INFO - 'tscrollx', 05:20:38 INFO - 'cart']}, 05:20:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:20:38 INFO - 'tsvgr_opacity', 05:20:38 INFO - 'tart', 05:20:38 INFO - 'tscrollx', 05:20:38 INFO - 'cart']}, 05:20:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s'], 05:20:38 INFO - 'tests': ['tp5o']}, 05:20:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'tests': ['tp5o']}, 05:20:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--disable-e10s', 05:20:38 INFO - '--xperf_path', 05:20:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:38 INFO - 'tests': ['tp5n']}, 05:20:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:20:38 INFO - 'talos_options': ['--xperf_path', 05:20:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:20:38 INFO - 'tests': ['tp5n']}}, 05:20:38 INFO - 'talos.zip': {'path': '', 05:20:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:20:38 INFO - Running post-action listener: _resource_record_post_action 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.160093Z] Finished populate-webroot step (success) 05:20:38 INFO - [mozharness: 2016-09-12 12:20:38.160182Z] Running create-virtualenv step. 05:20:38 INFO - Running pre-action listener: _resource_record_pre_action 05:20:38 INFO - Running main action method: create_virtualenv 05:20:38 INFO - Creating virtualenv /builds/slave/test/build/venv 05:20:38 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:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:20:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:20:38 INFO - Using real prefix '/usr' 05:20:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:20:38 INFO - Installing distribute.............................................................................................................................................................................................done. 05:20:40 INFO - Installing pip.................done. 05:20:40 INFO - Return code: 0 05:20:40 INFO - Installing pip>=1.5 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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', 'pip>=1.5'] 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 pip>=1.5 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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 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): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:20:40 INFO - Cleaning up... 05:20:40 INFO - Return code: 0 05:20:40 INFO - Installing psutil>=3.1.1 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', '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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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', 'psutil>=3.1.1'] 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 psutil>=3.1.1 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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:40 INFO - '_': '/tools/buildbot/bin/python'} 05:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:41 INFO - Downloading/unpacking psutil>=3.1.1 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:20:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:43 INFO - Installing collected packages: psutil 05:20:43 INFO - Running setup.py install for psutil 05:20:43 INFO - building 'psutil._psutil_linux' extension 05:20:43 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:43 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:43 INFO - building 'psutil._psutil_posix' extension 05:20:43 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:43 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:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:20:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:20:43 INFO - Successfully installed psutil 05:20:43 INFO - Cleaning up... 05:20:43 INFO - Return code: 0 05:20:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:20: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:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20: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:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:43 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:43 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:43 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:43 INFO - Using env: {'DISPLAY': ':0', 05:20:43 INFO - 'HOME': '/home/cltbld', 05:20:43 INFO - 'LANG': 'en_US.UTF-8', 05:20:43 INFO - 'LANGUAGE': 'en_US:en', 05:20:43 INFO - 'LOGNAME': 'cltbld', 05:20:43 INFO - 'MAIL': '/var/mail/cltbld', 05:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:43 INFO - 'MOZ_NO_REMOTE': '1', 05:20:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:43 INFO - 'NO_EM_RESTART': '1', 05:20:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:43 INFO - 'PWD': '/builds/slave/test', 05:20:43 INFO - 'SHELL': '/bin/bash', 05:20:43 INFO - 'SHLVL': '1', 05:20:43 INFO - 'TERM': 'linux', 05:20:43 INFO - 'TMOUT': '86400', 05:20:43 INFO - 'USER': 'cltbld', 05:20:43 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:43 INFO - '_': '/tools/buildbot/bin/python'} 05:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:20:45 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:45 INFO - Installing collected packages: mozsystemmonitor 05:20:45 INFO - Running setup.py install for mozsystemmonitor 05:20:45 INFO - Successfully installed mozsystemmonitor 05:20:45 INFO - Cleaning up... 05:20:45 INFO - Return code: 0 05:20:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:20:45 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:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:45 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:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:45 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:45 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:45 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:45 INFO - Using env: {'DISPLAY': ':0', 05:20:45 INFO - 'HOME': '/home/cltbld', 05:20:45 INFO - 'LANG': 'en_US.UTF-8', 05:20:45 INFO - 'LANGUAGE': 'en_US:en', 05:20:45 INFO - 'LOGNAME': 'cltbld', 05:20:46 INFO - 'MAIL': '/var/mail/cltbld', 05:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:46 INFO - 'MOZ_NO_REMOTE': '1', 05:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:46 INFO - 'NO_EM_RESTART': '1', 05:20:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:46 INFO - 'PWD': '/builds/slave/test', 05:20:46 INFO - 'SHELL': '/bin/bash', 05:20:46 INFO - 'SHLVL': '1', 05:20:46 INFO - 'TERM': 'linux', 05:20:46 INFO - 'TMOUT': '86400', 05:20:46 INFO - 'USER': 'cltbld', 05:20:46 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:46 INFO - '_': '/tools/buildbot/bin/python'} 05:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:46 INFO - Downloading/unpacking jsonschema==2.5.1 05:20:46 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:46 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:46 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:46 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:46 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:46 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:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:20:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:20:48 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:48 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:48 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:48 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:48 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:48 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:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:20:48 INFO - warning: no files found matching '*.txt' 05:20:48 INFO - no previously-included directories found matching 'build' 05:20:48 INFO - no previously-included directories found matching 'dist' 05:20:48 INFO - no previously-included directories found matching '.git*' 05:20:48 INFO - Installing collected packages: jsonschema, functools32 05:20:48 INFO - Running setup.py install for functools32 05:20:48 INFO - warning: no files found matching '*.txt' 05:20:48 INFO - no previously-included directories found matching 'build' 05:20:48 INFO - no previously-included directories found matching 'dist' 05:20:48 INFO - no previously-included directories found matching '.git*' 05:20:48 INFO - Successfully installed jsonschema functools32 05:20:48 INFO - Cleaning up... 05:20:48 INFO - Return code: 0 05:20:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:20:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:48 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:48 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:48 INFO - Using env: {'DISPLAY': ':0', 05:20:48 INFO - 'HOME': '/home/cltbld', 05:20:48 INFO - 'LANG': 'en_US.UTF-8', 05:20:48 INFO - 'LANGUAGE': 'en_US:en', 05:20:48 INFO - 'LOGNAME': 'cltbld', 05:20:48 INFO - 'MAIL': '/var/mail/cltbld', 05:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:48 INFO - 'MOZ_NO_REMOTE': '1', 05:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:48 INFO - 'NO_EM_RESTART': '1', 05:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:48 INFO - 'PWD': '/builds/slave/test', 05:20:48 INFO - 'SHELL': '/bin/bash', 05:20:48 INFO - 'SHLVL': '1', 05:20:48 INFO - 'TERM': 'linux', 05:20:48 INFO - 'TMOUT': '86400', 05:20:48 INFO - 'USER': 'cltbld', 05:20:48 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:48 INFO - '_': '/tools/buildbot/bin/python'} 05:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:20:48 INFO - Cleaning up... 05:20:48 INFO - Return code: 0 05:20:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:20:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:48 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:48 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:48 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:48 INFO - Using env: {'DISPLAY': ':0', 05:20:48 INFO - 'HOME': '/home/cltbld', 05:20:48 INFO - 'LANG': 'en_US.UTF-8', 05:20:48 INFO - 'LANGUAGE': 'en_US:en', 05:20:48 INFO - 'LOGNAME': 'cltbld', 05:20:48 INFO - 'MAIL': '/var/mail/cltbld', 05:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:48 INFO - 'MOZ_NO_REMOTE': '1', 05:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:48 INFO - 'NO_EM_RESTART': '1', 05:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:48 INFO - 'PWD': '/builds/slave/test', 05:20:48 INFO - 'SHELL': '/bin/bash', 05:20:48 INFO - 'SHLVL': '1', 05:20:48 INFO - 'TERM': 'linux', 05:20:48 INFO - 'TMOUT': '86400', 05:20:48 INFO - 'USER': 'cltbld', 05:20:48 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:48 INFO - '_': '/tools/buildbot/bin/python'} 05:20:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:20:48 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:48 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:48 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:48 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:48 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:48 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:50 INFO - Downloading blobuploader-1.2.4.tar.gz 05:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:20:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:20:50 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:50 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:50 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:50 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:50 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:50 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:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:20:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:51 INFO - Downloading docopt-0.6.1.tar.gz 05:20:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:20:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:20:51 INFO - Running setup.py install for blobuploader 05:20:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:20:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:20:51 INFO - Running setup.py install for requests 05:20:52 INFO - Running setup.py install for docopt 05:20:52 INFO - Successfully installed blobuploader requests docopt 05:20:52 INFO - Cleaning up... 05:20:52 INFO - Return code: 0 05:20:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:52 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:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:52 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:52 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:52 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:52 INFO - Using env: {'DISPLAY': ':0', 05:20:52 INFO - 'HOME': '/home/cltbld', 05:20:52 INFO - 'LANG': 'en_US.UTF-8', 05:20:52 INFO - 'LANGUAGE': 'en_US:en', 05:20:52 INFO - 'LOGNAME': 'cltbld', 05:20:52 INFO - 'MAIL': '/var/mail/cltbld', 05:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:52 INFO - 'MOZ_NO_REMOTE': '1', 05:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:52 INFO - 'NO_EM_RESTART': '1', 05:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:52 INFO - 'PWD': '/builds/slave/test', 05:20:52 INFO - 'SHELL': '/bin/bash', 05:20:52 INFO - 'SHLVL': '1', 05:20:52 INFO - 'TERM': 'linux', 05:20:52 INFO - 'TMOUT': '86400', 05:20:52 INFO - 'USER': 'cltbld', 05:20:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:52 INFO - '_': '/tools/buildbot/bin/python'} 05:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:52 INFO - Running setup.py (path:/tmp/pip-lsgjoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:52 INFO - Running setup.py (path:/tmp/pip-EEI7zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:52 INFO - Running setup.py (path:/tmp/pip-LfD7YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:52 INFO - Running setup.py (path:/tmp/pip-1ic4hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:52 INFO - Running setup.py (path:/tmp/pip-kkTux4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:52 INFO - Running setup.py (path:/tmp/pip-KZZzDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:52 INFO - Running setup.py (path:/tmp/pip-PATGtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:52 INFO - Running setup.py (path:/tmp/pip-C8nb9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:53 INFO - Running setup.py (path:/tmp/pip-o8JRy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:53 INFO - Running setup.py (path:/tmp/pip-vpLs0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:53 INFO - Running setup.py (path:/tmp/pip-l1kGXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:53 INFO - Running setup.py (path:/tmp/pip-5hoIqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:53 INFO - Running setup.py (path:/tmp/pip-3bxuyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:53 INFO - Running setup.py (path:/tmp/pip-r2i_Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:53 INFO - Running setup.py (path:/tmp/pip-m2L9K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:53 INFO - Running setup.py (path:/tmp/pip-R7ssna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:53 INFO - Running setup.py (path:/tmp/pip-Cf8LXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:20:53 INFO - Running setup.py install for manifestparser 05:20:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:20:53 INFO - Running setup.py install for mozcrash 05:20:53 INFO - Running setup.py install for mozdebug 05:20:53 INFO - Running setup.py install for mozdevice 05:20:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozfile 05:20:54 INFO - Running setup.py install for mozhttpd 05:20:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozinfo 05:20:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozInstall 05:20:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozleak 05:20:54 INFO - Running setup.py install for mozlog 05:20:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for moznetwork 05:20:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:20:54 INFO - Running setup.py install for mozprocess 05:20:54 INFO - Running setup.py install for mozprofile 05:20:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Running setup.py install for mozrunner 05:20:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Running setup.py install for mozscreenshot 05:20:55 INFO - Running setup.py install for moztest 05:20:55 INFO - Running setup.py install for mozversion 05:20:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:20:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:20:55 INFO - Cleaning up... 05:20:55 INFO - Return code: 0 05:20:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:55 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:55 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:55 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:55 INFO - Using env: {'DISPLAY': ':0', 05:20:55 INFO - 'HOME': '/home/cltbld', 05:20:55 INFO - 'LANG': 'en_US.UTF-8', 05:20:55 INFO - 'LANGUAGE': 'en_US:en', 05:20:55 INFO - 'LOGNAME': 'cltbld', 05:20:55 INFO - 'MAIL': '/var/mail/cltbld', 05:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:55 INFO - 'MOZ_NO_REMOTE': '1', 05:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:55 INFO - 'NO_EM_RESTART': '1', 05:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:55 INFO - 'PWD': '/builds/slave/test', 05:20:55 INFO - 'SHELL': '/bin/bash', 05:20:55 INFO - 'SHLVL': '1', 05:20:55 INFO - 'TERM': 'linux', 05:20:55 INFO - 'TMOUT': '86400', 05:20:55 INFO - 'USER': 'cltbld', 05:20:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:55 INFO - '_': '/tools/buildbot/bin/python'} 05:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:20:55 INFO - Running setup.py (path:/tmp/pip-sPTN2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:20:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:20:55 INFO - Running setup.py (path:/tmp/pip-ggXIa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:20:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:20:55 INFO - Running setup.py (path:/tmp/pip-z816BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:20:55 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:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:20:55 INFO - Running setup.py (path:/tmp/pip-3PUdCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:20:56 INFO - Running setup.py (path:/tmp/pip-wu0t2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:20:56 INFO - Running setup.py (path:/tmp/pip-W4qUQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:20:56 INFO - Running setup.py (path:/tmp/pip-Wr4J4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:20:56 INFO - Running setup.py (path:/tmp/pip-mrXE1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:20:56 INFO - Running setup.py (path:/tmp/pip-2VEkU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:20:56 INFO - Running setup.py (path:/tmp/pip-fYjGge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:20:56 INFO - Running setup.py (path:/tmp/pip-7xOuzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:20:56 INFO - Running setup.py (path:/tmp/pip-C187e1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:20:56 INFO - Running setup.py (path:/tmp/pip-XaXMrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:20:56 INFO - Running setup.py (path:/tmp/pip-aIjtJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:56 INFO - Running setup.py (path:/tmp/pip-EeOpiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:20:56 INFO - Running setup.py (path:/tmp/pip-oSnODP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:20:56 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:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:20:56 INFO - Running setup.py (path:/tmp/pip-XKzbiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:20:56 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:56 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:56 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:56 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:56 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:56 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:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:20:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:20:58 INFO - Downloading blessings-1.6.tar.gz 05:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:20:58 INFO - Installing collected packages: blessings 05:20:58 INFO - Running setup.py install for blessings 05:20:59 INFO - Successfully installed blessings 05:20:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:59 INFO - Reading from file tmpfile_stdout 05:20:59 INFO - Current package versions: 05:20:59 INFO - argparse == 1.2.1 05:20:59 INFO - blessings == 1.6 05:20:59 INFO - blobuploader == 1.2.4 05:20:59 INFO - docopt == 0.6.1 05:20:59 INFO - functools32 == 3.2.3-2 05:20:59 INFO - jsonschema == 2.5.1 05:20:59 INFO - manifestparser == 1.1 05:20:59 INFO - mozInstall == 1.12 05:20:59 INFO - mozcrash == 0.17 05:20:59 INFO - mozdebug == 0.1 05:20:59 INFO - mozdevice == 0.48 05:20:59 INFO - mozfile == 1.2 05:20:59 INFO - mozhttpd == 0.7 05:20:59 INFO - mozinfo == 0.9 05:20:59 INFO - mozleak == 0.1 05:20:59 INFO - mozlog == 3.3 05:20:59 INFO - moznetwork == 0.27 05:20:59 INFO - mozprocess == 0.23 05:20:59 INFO - mozprofile == 0.28 05:20:59 INFO - mozrunner == 6.12 05:20:59 INFO - mozscreenshot == 0.1 05:20:59 INFO - mozsystemmonitor == 0.3 05:20:59 INFO - moztest == 0.7 05:20:59 INFO - mozversion == 1.4 05:20:59 INFO - psutil == 3.1.1 05:20:59 INFO - requests == 1.2.3 05:20:59 INFO - wsgiref == 0.1.2 05:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 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 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:59 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:59 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:59 INFO - Using env: {'DISPLAY': ':0', 05:20:59 INFO - 'HOME': '/home/cltbld', 05:20:59 INFO - 'LANG': 'en_US.UTF-8', 05:20:59 INFO - 'LANGUAGE': 'en_US:en', 05:20:59 INFO - 'LOGNAME': 'cltbld', 05:20:59 INFO - 'MAIL': '/var/mail/cltbld', 05:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:59 INFO - 'MOZ_NO_REMOTE': '1', 05:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:59 INFO - 'NO_EM_RESTART': '1', 05:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:59 INFO - 'PWD': '/builds/slave/test', 05:20:59 INFO - 'SHELL': '/bin/bash', 05:20:59 INFO - 'SHLVL': '1', 05:20:59 INFO - 'TERM': 'linux', 05:20:59 INFO - 'TMOUT': '86400', 05:20:59 INFO - 'USER': 'cltbld', 05:20:59 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:59 INFO - '_': '/tools/buildbot/bin/python'} 05:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 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:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120a400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', '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:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:20:59 INFO - Using env: {'DISPLAY': ':0', 05:20:59 INFO - 'HOME': '/home/cltbld', 05:20:59 INFO - 'LANG': 'en_US.UTF-8', 05:20:59 INFO - 'LANGUAGE': 'en_US:en', 05:20:59 INFO - 'LOGNAME': 'cltbld', 05:20:59 INFO - 'MAIL': '/var/mail/cltbld', 05:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:59 INFO - 'MOZ_NO_REMOTE': '1', 05:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:20:59 INFO - 'NO_EM_RESTART': '1', 05:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:59 INFO - 'PWD': '/builds/slave/test', 05:20:59 INFO - 'SHELL': '/bin/bash', 05:20:59 INFO - 'SHLVL': '1', 05:20:59 INFO - 'TERM': 'linux', 05:20:59 INFO - 'TMOUT': '86400', 05:20:59 INFO - 'USER': 'cltbld', 05:20:59 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:59 INFO - '_': '/tools/buildbot/bin/python'} 05:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:20:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:20:59 INFO - Cleaning up... 05:20:59 INFO - Return code: 0 05:20:59 INFO - Running post-action listener: _resource_record_post_action 05:20:59 INFO - Running post-action listener: _start_resource_monitoring 05:20:59 INFO - Starting resource monitoring. 05:20:59 INFO - [mozharness: 2016-09-12 12:20:59.754929Z] Finished create-virtualenv step (success) 05:20:59 INFO - [mozharness: 2016-09-12 12:20:59.755359Z] Running install step. 05:20:59 INFO - Running pre-action listener: _resource_record_pre_action 05:20:59 INFO - Running main action method: install 05:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:20:59 INFO - Reading from file tmpfile_stdout 05:20:59 INFO - Detecting whether we're running mozinstall >=1.0... 05:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:00 INFO - Reading from file tmpfile_stdout 05:21:00 INFO - Output received: 05:21:00 INFO - Usage: mozinstall [options] installer 05:21:00 INFO - Options: 05:21:00 INFO - -h, --help show this help message and exit 05:21:00 INFO - -d DEST, --destination=DEST 05:21:00 INFO - Directory to install application into. [default: 05:21:00 INFO - "/builds/slave/test"] 05:21:00 INFO - --app=APP Application being installed. [default: firefox] 05:21:00 INFO - mkdir: /builds/slave/test/build/application 05:21:00 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:21:00 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:21:16 INFO - Reading from file tmpfile_stdout 05:21:16 INFO - Output received: 05:21:16 INFO - /builds/slave/test/build/application/firefox/firefox 05:21:16 INFO - Running post-action listener: _resource_record_post_action 05:21:16 INFO - [mozharness: 2016-09-12 12:21:16.076038Z] Finished install step (success) 05:21:16 INFO - [mozharness: 2016-09-12 12:21:16.076314Z] Running run-tests step. 05:21:16 INFO - Running pre-action listener: _resource_record_pre_action 05:21:16 INFO - Running main action method: run_tests 05:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:21:16 INFO - Python 2.7.3 05:21:16 INFO - Return code: 0 05:21:16 INFO - grabbing minidump binary from tooltool 05:21:16 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:21:16 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 0x1299570>, '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 0x129a470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x129a900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:21:16 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:21:16 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:21:16 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:21:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:21:16 INFO - Return code: 0 05:21:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:21:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:21:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:21:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:21:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:21:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:21:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:21:16 INFO - Using env: {'DISPLAY': ':0', 05:21:16 INFO - 'HOME': '/home/cltbld', 05:21:16 INFO - 'LANG': 'en_US.UTF-8', 05:21:16 INFO - 'LANGUAGE': 'en_US:en', 05:21:16 INFO - 'LOGNAME': 'cltbld', 05:21:16 INFO - 'MAIL': '/var/mail/cltbld', 05:21:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:21:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:21:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:16 INFO - 'MOZ_NO_REMOTE': '1', 05:21:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:21:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:16 INFO - 'NO_EM_RESTART': '1', 05:21:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:16 INFO - 'PWD': '/builds/slave/test', 05:21:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:21:16 INFO - 'SHELL': '/bin/bash', 05:21:16 INFO - 'SHLVL': '1', 05:21:16 INFO - 'TERM': 'linux', 05:21:16 INFO - 'TMOUT': '86400', 05:21:16 INFO - 'USER': 'cltbld', 05:21:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991', 05:21:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:16 INFO - '_': '/tools/buildbot/bin/python'} 05:21:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yk3N7HGuRo6WD8N1ZA_L1g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:21:16 INFO - mozversion application_buildid: 20160910055116 05:21:16 INFO - mozversion application_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:21:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:21:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:21:16 INFO - mozversion application_name: Firefox 05:21:16 INFO - mozversion application_remotingname: firefox-dev 05:21:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:16 INFO - mozversion application_vendor: Mozilla 05:21:16 INFO - mozversion application_version: 50.0a2 05:21:16 INFO - mozversion platform_buildid: 20160910055116 05:21:16 INFO - mozversion platform_changeset: 8663d41e015f3c9933a08a65c8349b86f2263354 05:21:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:21:16 INFO - mozversion platform_version: 50.0a2 05:21:16 INFO - using testdate: 1473682876 05:21:16 INFO - actual date: 1473682876 05:21:16 INFO - starting webserver on 'localhost:46035' 05:21:16 INFO - SUITE-START | Running 2 tests 05:21:16 INFO - TEST-START | dromaeo_css 05:21:16 INFO - operating with platform_type : linux_ 05:21:16 INFO - Initialising browser for dromaeo_css test... 05:21:16 INFO - TEST-INFO | started process 11366 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWwM6E4/profile http://localhost:46035/getInfo.html) 05:21:20 INFO - PROCESS | 11366 | __metrics Screen width/height:1600/1200 05:21:20 INFO - PROCESS | 11366 | colorDepth:24 05:21:20 INFO - PROCESS | 11366 | Browser inner width/height: 1024/697 05:21:20 INFO - PROCESS | 11366 | __metrics 05:21:21 ERROR - PROCESS | 11366 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:21:22 INFO - TEST-INFO | 11366: exit 0 05:21:22 INFO - Browser initialized. 05:21:22 INFO - Running cycle 1/1 for dromaeo_css test... 05:21:22 INFO - TEST-INFO | started process 11491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWwM6E4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:21:33 INFO - PROCESS | 11491 | RSS: Main: 152924160 05:21:33 INFO - PROCESS | 11491 | 05:22:23 INFO - PROCESS | 11491 | 1473682943435 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:32 INFO - PROCESS | 11491 | Cycle 1(1): loaded http://localhost:46035/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46035/tests/dromaeo/cssquery-ext.html) 05:24:33 INFO - PROCESS | 11491 | RSS: Main: 159858688 05:24:33 INFO - PROCESS | 11491 | 05:27:35 INFO - PROCESS | 11491 | Cycle 1(1): loaded http://localhost:46035/tests/dromaeo/cssquery-ext.html (next: http://localhost:46035/tests/dromaeo/cssquery-jquery.html) 05:27:35 INFO - PROCESS | 11491 | RSS: Main: 155758592 05:27:35 INFO - PROCESS | 11491 | 05:30:37 INFO - PROCESS | 11491 | Cycle 1(1): loaded http://localhost:46035/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46035/tests/dromaeo/cssquery-mootools.html) 05:30:38 INFO - PROCESS | 11491 | RSS: Main: 156426240 05:30:38 INFO - PROCESS | 11491 | 05:33:40 INFO - PROCESS | 11491 | Cycle 1(1): loaded http://localhost:46035/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46035/tests/dromaeo/cssquery-prototype.html) 05:33:40 INFO - PROCESS | 11491 | RSS: Main: 154214400 05:33:40 INFO - PROCESS | 11491 | 05:36:43 INFO - PROCESS | 11491 | Cycle 1(1): loaded http://localhost:46035/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46035/tests/dromaeo/cssquery-yui.html) 05:36:43 INFO - PROCESS | 11491 | RSS: Main: 157036544 05:36:43 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | Cycle 1(1): loaded http://localhost:46035/tests/dromaeo/cssquery-yui.html (next: http://localhost:46035/tests/dromaeo/cssquery-dojo.html) 05:39:46 INFO - PROCESS | 11491 | RSS: Main: 157818880 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | __start_tp_report 05:39:46 INFO - PROCESS | 11491 | _x_x_mozilla_page_load 05:39:46 INFO - PROCESS | 11491 | _x_x_mozilla_page_load_details 05:39:46 INFO - PROCESS | 11491 | |i|pagename|runs| 05:39:46 INFO - PROCESS | 11491 | |0;dojo.html;3287.6712328767117;3322.1196382958537;3328.384248913951;3329.650386709396;3349.2129349602856;5695.886082278352;5749.655020698756;5778.191053252548;5790.189373487715;5817.941820581793;5649.661020338773;5662.801801936933;5697.316322041351;5707.800226992056;5717.571182161334;5163.457836927116;5202.08963431398;5222.92165617516;5260.184671375943;5314.229436731672;5182.585393168538;5214.426413094557;5231.581473482112;5238.633295669305;5276.736163191844;5107.591392688594;5116.539511443969;5125.743712814363;5126.436092029874;5346.064438723227;5123.462036486171;5132.307138536287;5135.794568217267;5151.330327057477;5173.560247378978;4974.651774375759;5033.622478314104;5034.270030845543;5047.242913562958;5091.439941606421;5546.389897111315;5610.074337734269;5623.212750214973;5637.577181711388;5675.091985282335;6122.265328160604;6157.2919114301985;6161.229846269216;6177.62934223948;6202.689865506707;6198.938010619926;6207.81376558704;6209.59637623553;6228.781992630236;6236.064590311445;5667.716614169275;5672.432756724335;5686.289213848269;5715.828525144252;5750.741216645261;6330.113784070234;6336.778212762531;6349.777757778455;6351.301356850743;6359.427651511386;6067.423594758491;6068.817935461943;6073;6078.787242446493;6083.270007599116;5952.583319167703;5998;6032.457078862835;6037.034074548052;6079.726412311456;6014.097885317237;6058.273007239071;6061.121137435135;6066.393360663898;6091;6023.307319658208;6037.09443583466;6054.636721796706;6065.029595264737;6078.908816367758;4619.1685496610935;4622.19111655459;4636.744979026119;4655.557722016403;4671.813127474863;4196.370544418301;4199.916001680011;4200.6219440250525;4200.915981680349;4233.301505251599;5922.1412895130825;5935.139404786281;5937.614055086406;5942.465178133989;5952.85117872048;5976.850578735566;6110.144579758838;6117.9492029047315;6129.264488261437;6189.133521307021;5942.494887934487;5956.434138756811;5968.4926781224085;5972.910406343908;5988.401159884064;5334.51989320963;5354.491323324278;5355.22349259363;5359.14253719403;5383.461653834664;6149.907751383733;6262.2172228471445;6306;6307.3377295383325;6465.967670161714;6189.04069869171;6196.287426945959;6202.7829025985675;6204.162438070803;6214.471769899428;6395.840103997437;6397.840053998687;6400.455961243347;6407.295197528175;6416.486681065597;5962.016267315845;6041.909371359346;6048.002079656983;6068.969655151696;6110.602810817282;6251.031090181029;6272.153259309935;6290.402411771058;6299.559030867796;6301.527385446201;6088.269407671108;6120.7857724981195;6127.050307202391;6134.601250918676;6136.478399336106;5810.273715785527;5892.1161825726485;5911.704414779158;5915.615484993462;6117.663528505975;5925.318588362394;5935.961206788708;5942.692607626315;5946.07835785454;5969.70151492415;5940.019896716994;5956;5984.341722410444;5995.370486098896;6016.338202797601;5698.8290351289525;5806.303243610793;5857.26784151981;5868.149118377726;5869.73586188614 05:39:46 INFO - PROCESS | 11491 | |1;ext.html;590.6869359239605;640.1517988665018;660.6465540935599;689.6344937183295;729.0667945030357;41857.79071104648;46440.53559464405;46675.29987050197;46729.76635116824;47040.76479617606;46591.53408465914;47142.057158856805;47230.291545626846;47247.29129063067;47355.76322118384;9031.142041506062;9153.816923661523;9184.173424391804;9219.170274675278;9224.907750922506;9169;9229.723108306727;9241.214496767783;9247.768805779875;9248.491332976684;9277.072292770736;9304.674336398228;9304.906950930472;9311.534423278808;9362.438253704799;9253.583588738524;9291.117343852356;9298.86051709225;9300.30247731416;9300.906990930072;9083.81832363349;9142.177204051668;9191.494467804268;9200.539973001323;9207.40151890125;44644.553554464364;44822;45012.77493612544;45050.32424513635;45247.77376113131;3192.8722851085927;3590.2819436112622;3709.7959612221316;3832.5209348831395;3851.691864650821;33492.49761253583;33586.66413335884;33852.6614733852;33925.83037084799;33928.83035584831;4349.195398851222;4380.08018316154;4500.8649740507835;4536.319552067183;4580.725156490621;397502;397722;402530;404650;407733.96133019443;68326.65836670884;68925.65537172282;68934;68971;69030.30969690239;38759.61240387616;39198.020049498424;39199.80400097981;70378;70614.29385706077;18573.90713046426;18989;19112.2355105797;19127.13927873249;19264.32574859873;14599.41602335916;14614.196219207834;14682.265886705622;14690.19203943772;14720.19038952868;20603.690944635844;20937.685934710993;20977.475563111046;21008.789912100685;21292.828894410955;7546.509476883985;7611.543307401574;7761.573113478813;7822.374210063182;8014.959925200336;1031.1802117316602;1086.2124959404528;1097.9066779323846;1114.7213196700825;1236.9010479161645;33302.50046249308;33321.666783332344;33575;33655.83172084173;33829.154271143416;1169.4503583315827;1177.8083429746096;1179.687382843547;1214.5688280660415;1241.2986662535639;17110.401135960183;17792.46622601324;17847.37534186297;17878.731819022723;17981.460556183334;1122459;1143311;1144455;1145802;1149134.5086549027;63329.36670633327;63626.36373636297;63705;64079.35920640734;64353.678231608545;1468.4126349460087;1483.7403454395437;1489.6275931017246;1500.5423345879612;1522.0335087219696;34926;35298.11754706153;35616.287674245854;35714.821425893744;36075;57071;57373.13940290816;57774;57816;57927.4207257922;525099;536245;536265;536431;537838;30992.605332759627;31527.21181970469;31650.366992660478;31768;33157.66842331546;53120.734396327774;53148.2027769576;53802.73098634482;54005.729971351466;54575.727121364136;45242.773786130856;45857.16571337243;46927.53072469368;47464.52535474739;48026.27960580524;46649.53350466452;46669.76665116653;46715.79844085867;46912.7654361726;46937.2959405616;44138.11723765443;45658;45777.54222457733;45794.771026144655;46799.298010529186;39246.803765980985;39609.40585891157;40302.1939561213;40561;40827.7958610205;30466.390672186877;30480.542791857697;30665.54001689932;30861.691383085883;30924.536131958488 05:39:46 INFO - PROCESS | 11491 | |2;jquery.html;411.8270326462885;417.9540250572438;425.69988158348366;430.5586773557105;440.99559004409946;19252.326168584103;20762.792372076274;20776.06507707153;21169.470763230926;21680.891595542;20310.898445507788;20540.691889621667;20569.28007519737;20853.687194692055;21027.053782579784;5975.253093363341;6197;6198.907016394758;6200.534959878005;6205.813825585217;6039.365866584011;6059.818205453844;6072.149899014142;6094.664793436359;6106.603070800384;5881.7353219105025;6020.698965051752;6040.305364883051;6041.939580604182;6042.728077236513;5545.140503221987;5707.885842283152;5825.941740582624;5875.853103672443;5955.493783028448;5618.269624948731;5647.152927060933;5668.234788303567;5752.252207213036;5781.942180578182;14069.64825879351;14293.142411455243;14296.285185740775;22464.764437955906;24154.7584524156;2769.1969328894597;2785.8189217700783;2872.626558547396;3128.71841534263;3167.0657156138996;11273.26723762961;12471.251724896536;12777.936110319482;15332.15673137977;15583.766243506356;2010.577778666482;2032.674772036482;2066.4627196928755;2090.832733381326;2124.0654112190587;89706;89957.55021224852;91231.54384228103;91671.54164229135;91761.54119229427;11499.3675347855;11656.650300491;11772.587959421378;11795.115366347558;11954.940225298991;16684.916575417046;16735.07957062349;16767.748483772753;16780;16782.66434671324;8409.663613455394;8518.105598912149;8709.782255443537;8820.162084601952;8976.910230897738;8432.156784321518;8469.407141500036;8492.490450572861;8593.871091933626;8641.913580864237;4955.776990035385;4997.800087996512;5007.84976450707;5058.54473097423;5152.845414637567;2040.3776848061186;2160.6867004284604;2182.4871155278497;2186.2348178137527;2201.76881427451;1024;1024.902634249745;1053.4153544782566;1073.608133031438;1113.0427832064418;19925.900370498344;19951.20195192176;20142.69785953212;20345.796542034685;20480.59038819215;1033.7932413517326;1053.0469924718143;1064.21248276275;1072.51736718477;1104.8563686720674;10451.268411211142;11447.19869609136;11471.885281147082;11513.078953683853;11544.307341559534;513087;514891;518478;524141;525119;94945;95701.52149239209;95762;100349;102655.48672256591;1072.7050061233224;1075.3386667199616;1107.2968664897849;1107.6953837694698;1116.4752566293828;18204.72692909581;18744.531386714894;18885.43343699691;19262.518437038627;19447;42134;42459.36310955277;44465.99903004308;45490.77254613706;46771.532284676716;58412.70793646004;58930.70534647299;64422;64861.67569162124;65382.67308663426;8414.158584141536;8901.287896968357;8922.063183365653;8959.148880856308;9126.726198214064;10493.632722854663;11228.943855280671;13267.336633168496;13742.244176569984;14049.789253161007;11800.409979501163;11940.820887686517;12119.151659383759;12302.81545776832;12452.128351015705;11822.998625580172;11855.58505452305;11908.166428349932;12042.337671428155;12274.079444041554;11847.76304473923;12030.458629361874;12047.51809927593;12157.635270941886;12184.756304873676;8490.23587877094;8602.05377408497;8616.396852220285;8723.738287851374;9004.459732416077;11285;12004.2197257178;12172.208806427554;12185.268883866996;12224 05:39:46 INFO - PROCESS | 11491 | |3;mootools.html;1548.3806477409034;1566.9059856408617;1747.3884140550801;1865.9067046647672;1910.8853468791865;8268.627911743972;8920.420172688771;9006.189442950134;9591.66429174979;9595.808083838328;9070.863937040933;9341.392809467397;9348.579313930872;9349.345545811795;9419.764505887339;2476.752324767522;2513.710923243823;2517.345490172559;2526.519961207374;2539.2255362114547;2414.541237164942;2501.062101711858;2503.7746602805746;2525.9621105683336;2532.556802559554;2474.7648973347505;2475.121331927167;2499.687539057618;2505.0105208442656;2523.3060908250195;2413.2398294537165;2425.454272788626;2429.7327293997646;2455.7667021632915;2456.6683497727936;2316.5366926614743;2316.6061769499056;2325.2442956039185;2330.5222429401933;2472.4807790364043;9316.534173291377;9493.288003399774;9507.714768556954;9513.476758778264;9542.33203675743;5451.454854514556;5556.222128901957;5588.608797384185;5612.270404847384;5614.578906582023;3294.5717056782464;3386.339663765567;3425.1265927188656;3441.4493681011168;3452.8446219920165;1111.199856232908;1141.4064686363045;1153.3771763247837;1158.7450760832603;1212.630147804928;12888.291143987077;12939.417726202344;12961.740765184644;12995.740085198338;13020.86979130206;4729.456112547033;4859.222524396079;4878.609711223165;4954.1825598775795;4969.403671559436;10764.838527422095;10803.297785643908;10813.62152324665;10872.130229581615;10899.618513352154;5527.39198688144;5550.8057217997175;5554.833354999357;5556.249906262684;5556.749946252428;4509.368688383629;5008.173651347487;5130.102232109366;5133.25567792668;5141.151709967889;4311.482622085307;4368.716033457815;4398.47218333802;4443.577860103285;4507.256302710081;1223.473906220334;1227.3495047624774;1229.9139060265695;1235.1600911380167;1235.5366737473448;1042.5048102151568;1046.40877903984;1051.374432212832;1059.1685526861377;1087.1900434176432;6219.222597175353;6335.3981371769605;6418.454431373386;6462.450691691259;6569.244536878225;500.9855043536867;509.55923126495316;512.3160580624907;513.4839486316303;514.0284861611557;4416.094700586372;4429.15845989261;4454.7549884756;4488;4506.278995360726;11428.314301141958;11772.470238839272;11808.822867656992;11822.34977076269;11836.88163118375;10855.131589472981;11355.829662555068;11362.375069371099;11581.652550423332;11707.297562146128;8304.750857474286;8473.449225800427;8512.489250644981;8514.361422893307;8529.275011623957;6484.092227088117;6655.434288085567;6691.8327041822395;6697.665116744241;6753.3246675332075;10403.687889363331;10808.621698240524;10825.404602746925;10828.837567436653;10895.07391871668;10523.421211833116;10968.396738179477;10975.396353200651;10991.670249892515;11102.2783519071;8122.55325957078;8219.383546234176;8320.833583328178;8389.412741108064;8483.533405662747;9272.490013049077;9293.67472138472;9352.906470935204;9353.204977576983;9362.063793620584;8167.754967350989;8239.217274358927;8292.33661307082;8393.076761556345;8532.914670853212;8184.140665230065;8251.793705157179;8253.257206851413;8266.710665126691;8289;7508.699652013858;7512.924870751441;7582;7658.195889431529;7688.730894418669;5595.440455954372;5645.266115404971;5706.115552089433;5753.683547404766;5865.589408741347;5296.814611488579;5438.809641662523;5462.126059830408;5488.286522752017;5492.395836458067 05:39:46 INFO - PROCESS | 11491 | |4;prototype.html;335.60063524405956;351.1274482909968;352.6103655460716;355.44195612887745;358.27806968957555;5704.743286552105;5829.621074630146;5878.854218210355;5911.290645122592;5922.792702255417;5800.593958422912;5801.854953626162;5844.912326315109;5872.324682661484;5882.735276912538;4567.1550763108935;4573;4573.656975726825;4586.990862010369;4591.77041147943;4443.911121777563;4510;4524.660650451213;4554.385158003677;4593.955689718076;4468.262736648449;4478.776061196943;4480.977595112036;4485.506594274643;4489.685721999462;4492.775361231942;4492.955070449303;4508.706934049309;4542.818287268506;4561.270196768534;3884.7474914130776;3923.8822835314986;3946.565877753445;3972.5828787977134;3991.6207960243732;5604.346717718242;5626.634268772557;5645;5659.236003139565;5677.716114194273;5171.405288391846;5178.394697643705;5187.221916712486;5201.947980520222;5231.163013917755;5921.259842519685;6095.664738439384;6110.663913484756;6112.572119951606;6135.78524751636;4356.978215108904;4372.650187984986;4380.255356589388;4381.474223093217;4383.320585309282;6193.411625895577;6357.872842543169;6409.743610255584;6419.197600299963;6474.12599299098;4443.244648409778;4458.77706114693;4475.769163480004;4483.932741008887;4488.4165058542185;4542.750148741785;4545.568171023748;4554.521775213621;4554.656376368979;4554.726716397026;5182.663126896739;5203.115470370046;5210.505002024802;5230.34620672416;5244.475552444725;5904.970475147683;5915.615484993462;6044.697765111727;6176.505879529627;6214.601714614176;1545.65995480994;1568.2629164292764;1571.4657016614178;1575.3777257983033;1582.7970742235984;948.6300342866288;979.4025644356936;1002.7844013536978;1013.3735354755511;1028.228828378716;5208.765605547759;5222.694326418396;5283.286756287929;5305.31030965972;5322.494363035506;5462.371827239918;5598.804041858515;5602.915956260659;5610.18652295423;5616.719164041863;4937.851864444073;5249.343832020997;5260.342457192851;5316.893662126736;5333.5199832015305;2942.558616207586;2984.7612191024664;2987.805792623516;2990.7607391408637;2992.2968102495897;5757.992351338499;5759.832092349191;5790.305163380422;5883.676397798162;5889.94110058894;5966.791162309299;6383.202099737533;6388.425041746265;6398.424141827258;6416.743330266737;6589.209294884645;6592.5055620828625;6603.273639899608;6607.6365799880505;6612.437942774918;6097.634141951497;6291.370862913672;6314.652694101685;6316.6841657916;6350.618962862243;6233.787005612942;6245.812625621239;6286.465650419766;6305.936940630719;6343.651099189405;6363;6454.548181627241;6502.252240992158;6599.703013364314;6600.53796234259;4465.620422264144;4494.595486406109;4510.954890451054;4525.185466616041;4543.136804007228;6174.845628859314;6361.332060133805;6383.904241436289;6447.258565265054;6453.096566480603;6103.176071230505;6114.694265286629;6163.9383606163365;6179.16581261524;6202.72087756043;6007.791572930077;6154.676744499954;6162.352952940056;6189.164462797645;6228.626282423069;6035.521297282194;6091.451769340781;6100.54245931548;6108.5723999319625;6116.969415152896;6191;6200.937990620036;6204.348543402878;6227.657478838707;6247.437730604267;5768.480836724715;5982.970085149546;6012.488938440281;6045.969770151121;6051.576389652682 05:39:46 INFO - PROCESS | 11491 | |5;yui.html;766.8581312457196;802.690963978868;821.4414198345127;880.7754022724205;912.1334732004595;16737.246823892925;16924.49226523203;17165.82834171658;17844.55388615285;17873;14245.643858903533;14383.712325753479;14392.49626263081;14422.927885360585;14450.421983120663;725.2276325713125;877.8200468903859;881.889763779526;894.239896088323;899.8785164002874;891.2780647675371;892.977675558113;893.0355216366341;893.4817805672694;894.9686760963367;957.3968399908048;957.4351132831628;957.7701351675618;959.2086528613886;960.4909398019026;949.3449519831329;949.3686698345592;949.6296444386695;949.6486300068949;949.6771097826772;691.5401258163315;695.9234484206734;696.8292768271806;697.8290318871858;698.3958875572594;16143.434979775651;16194.271257793309;16237.51287461378;16280.26738796757;16284.592885177846;15079.924600377037;15138;15618.375264989387;15679.372825086983;16399.754003689955;13689.178649281075;13707.314634268247;13762.587122386345;13863.168209907439;13972.161670299713;4088.407180958774;4104.91790164195;4136.875893723193;4171.937420938658;4192.4549808525;92182.53908730413;92784.53607731986;92835.53582232114;92884.07115928821;93247;12187.817182742267;12473.75052498945;12584.433700483318;12965.481380744854;13090.934545327213;23230;23496.882515587655;23814.404639884142;23999.7600024001;24142.51714965691;9131.091147573545;9219.124183202584;9257.120573545502;9290.489023103793;9394.389364691273;5118;5137.40919794221;5176.7411629418375;5227.895442091137;5305.522502974673;3690.520232369775;3760.7555508891833;3787.886363409047;3807.828647710804;3811;627.3412916437723;641.4515589171285;650.72344253692;667.8163505036154;700.7092056796372;650.3756393861945;658.6212927566669;701.894715792625;775.8254392761696;846.6190214403543;10672.466376681135;10861;10920.017198452177;11124.888751112385;11349.829752553718;745.2956955676942;778.3928535742131;821.2444551013082;823.4277177361796;835.4277320035795;4617;4860.295257187688;4874.488178741252;4887.65786394956;4939.950600493949;73772;74228.62885685537;84074;127200.36399817941;127347;107059.46470267755;107704.46147769211;108083;111896;114000.42999784948;60497.69751151216;89292;90372.99999999869;92134;93990;9497.952510237405;9658.7585310365;9834.311598188058;9834.459104749307;9835.557399917165;53082;53441.732791335795;53477.465225347245;54518.72740636271;56180.71909640425;72834.63582682052;74840;81284;81432.59283703781;81892;12577.056720745797;13037.217766934014;13301.401436935168;13402.798958015443;13500.392482338122;15761.211939403212;15767.526974190792;16197.67604647924;16461.25924333432;17008.234629441456;16117.838821611635;16210.270537826062;16529.33882644681;17053.91473042627;17251;14856.480023199136;15671.138087405303;16266.186690665656;16691.49925502237;16946;14061.718765624426;15249.16129612882;15566.922165389553;16080.597985049999;16116.113613751357;11208.66374008781;11861.76276474483;12967.22196668203;13393.66515837112;13640.386182621607;12025.218360806519;12643.367831608199;12792.424340904496;12873.871261287268;13078.869211307765 05:39:46 INFO - PROCESS | 11491 | __end_tp_report 05:39:46 INFO - PROCESS | 11491 | __start_cc_report 05:39:46 INFO - PROCESS | 11491 | _x_x_mozilla_cycle_collect,341 05:39:46 INFO - PROCESS | 11491 | __end_cc_report 05:39:46 INFO - PROCESS | 11491 | __startTimestamp1473683986621__endTimestamp 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | ------- Summary: start ------- 05:39:46 INFO - PROCESS | 11491 | Number of tests: 6 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | [#0] dojo.html Cycles:165 Average:5699.04 Median:5942.69 stddev:660.17 (11.1%) stddev-sans-first:634.52 05:39:46 INFO - PROCESS | 11491 | Values: 3287.7 3322.1 3328.4 3329.7 3349.2 5695.9 5749.7 5778.2 5790.2 5817.9 5649.7 5662.8 5697.3 5707.8 5717.6 5163.5 5202.1 5222.9 5260.2 5314.2 5182.6 5214.4 5231.6 5238.6 5276.7 5107.6 5116.5 5125.7 5126.4 5346.1 5123.5 5132.3 5135.8 5151.3 5173.6 4974.7 5033.6 5034.3 5047.2 5091.4 5546.4 5610.1 5623.2 5637.6 5675.1 6122.3 6157.3 6161.2 6177.6 6202.7 6198.9 6207.8 6209.6 6228.8 6236.1 5667.7 5672.4 5686.3 5715.8 5750.7 6330.1 6336.8 6349.8 6351.3 6359.4 6067.4 6068.8 6073.0 6078.8 6083.3 5952.6 5998.0 6032.5 6037.0 6079.7 6014.1 6058.3 6061.1 6066.4 6091.0 6023.3 6037.1 6054.6 6065.0 6078.9 4619.2 4622.2 4636.7 4655.6 4671.8 4196.4 4199.9 4200.6 4200.9 4233.3 5922.1 5935.1 5937.6 5942.5 5952.9 5976.9 6110.1 6117.9 6129.3 6189.1 5942.5 5956.4 5968.5 5972.9 5988.4 5334.5 5354.5 5355.2 5359.1 5383.5 6149.9 6262.2 6306.0 6307.3 6466.0 6189.0 6196.3 6202.8 6204.2 6214.5 6395.8 6397.8 6400.5 6407.3 6416.5 5962.0 6041.9 6048.0 6069.0 6110.6 6251.0 6272.2 6290.4 6299.6 6301.5 6088.3 6120.8 6127.1 6134.6 6136.5 5810.3 5892.1 5911.7 5915.6 6117.7 5925.3 5936.0 5942.7 5946.1 5969.7 5940.0 5956.0 5984.3 5995.4 6016.3 5698.8 5806.3 5857.3 5868.1 5869.7 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | [#1] ext.html Cycles:180 Average:83173.42 Median:45031.55 stddev:207396.70 (460.6%) stddev-sans-first:207885.81 05:39:46 INFO - PROCESS | 11491 | Values: 590.7 640.2 660.6 689.6 729.1 41857.8 46440.5 46675.3 46729.8 47040.8 46591.5 47142.1 47230.3 47247.3 47355.8 9031.1 9153.8 9184.2 9219.2 9224.9 9169.0 9229.7 9241.2 9247.8 9248.5 9277.1 9304.7 9304.9 9311.5 9362.4 9253.6 9291.1 9298.9 9300.3 9300.9 9083.8 9142.2 9191.5 9200.5 9207.4 44644.6 44822.0 45012.8 45050.3 45247.8 3192.9 3590.3 3709.8 3832.5 3851.7 33492.5 33586.7 33852.7 33925.8 33928.8 4349.2 4380.1 4500.9 4536.3 4580.7 397502.0 397722.0 402530.0 404650.0 407734.0 68326.7 68925.7 68934.0 68971.0 69030.3 38759.6 39198.0 39199.8 70378.0 70614.3 18573.9 18989.0 19112.2 19127.1 19264.3 14599.4 14614.2 14682.3 14690.2 14720.2 20603.7 20937.7 20977.5 21008.8 21292.8 7546.5 7611.5 7761.6 7822.4 8015.0 1031.2 1086.2 1097.9 1114.7 1236.9 33302.5 33321.7 33575.0 33655.8 33829.2 1169.5 1177.8 1179.7 1214.6 1241.3 17110.4 17792.5 17847.4 17878.7 17981.5 1122459.0 1143311.0 1144455.0 1145802.0 1149134.5 63329.4 63626.4 63705.0 64079.4 64353.7 1468.4 1483.7 1489.6 1500.5 1522.0 34926.0 35298.1 35616.3 35714.8 36075.0 57071.0 57373.1 57774.0 57816.0 57927.4 525099.0 536245.0 536265.0 536431.0 537838.0 30992.6 31527.2 31650.4 31768.0 33157.7 53120.7 53148.2 53802.7 54005.7 54575.7 45242.8 45857.2 46927.5 47464.5 48026.3 46649.5 46669.8 46715.8 46912.8 46937.3 44138.1 45658.0 45777.5 45794.8 46799.3 39246.8 39609.4 40302.2 40561.0 40827.8 30466.4 30480.5 30665.5 30861.7 30924.5 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | [#2] jquery.html Cycles:180 Average:30754.07 Median:11920.79 stddev:85735.93 (719.2%) stddev-sans-first:85946.17 05:39:46 INFO - PROCESS | 11491 | Values: 411.8 418.0 425.7 430.6 441.0 19252.3 20762.8 20776.1 21169.5 21680.9 20310.9 20540.7 20569.3 20853.7 21027.1 5975.3 6197.0 6198.9 6200.5 6205.8 6039.4 6059.8 6072.1 6094.7 6106.6 5881.7 6020.7 6040.3 6041.9 6042.7 5545.1 5707.9 5825.9 5875.9 5955.5 5618.3 5647.2 5668.2 5752.3 5781.9 14069.6 14293.1 14296.3 22464.8 24154.8 2769.2 2785.8 2872.6 3128.7 3167.1 11273.3 12471.3 12777.9 15332.2 15583.8 2010.6 2032.7 2066.5 2090.8 2124.1 89706.0 89957.6 91231.5 91671.5 91761.5 11499.4 11656.7 11772.6 11795.1 11954.9 16684.9 16735.1 16767.7 16780.0 16782.7 8409.7 8518.1 8709.8 8820.2 8976.9 8432.2 8469.4 8492.5 8593.9 8641.9 4955.8 4997.8 5007.8 5058.5 5152.8 2040.4 2160.7 2182.5 2186.2 2201.8 1024.0 1024.9 1053.4 1073.6 1113.0 19925.9 19951.2 20142.7 20345.8 20480.6 1033.8 1053.0 1064.2 1072.5 1104.9 10451.3 11447.2 11471.9 11513.1 11544.3 513087.0 514891.0 518478.0 524141.0 525119.0 94945.0 95701.5 95762.0 100349.0 102655.5 1072.7 1075.3 1107.3 1107.7 1116.5 18204.7 18744.5 18885.4 19262.5 19447.0 42134.0 42459.4 44466.0 45490.8 46771.5 58412.7 58930.7 64422.0 64861.7 65382.7 8414.2 8901.3 8922.1 8959.1 9126.7 10493.6 11228.9 13267.3 13742.2 14049.8 11800.4 11940.8 12119.2 12302.8 12452.1 11823.0 11855.6 11908.2 12042.3 12274.1 11847.8 12030.5 12047.5 12157.6 12184.8 8490.2 8602.1 8616.4 8723.7 9004.5 11285.0 12004.2 12172.2 12185.3 12224.0 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | [#3] mootools.html Cycles:180 Average:6169.66 Median:4619.41 stddev:3525.23 (76.3%) stddev-sans-first:3518.02 05:39:46 INFO - PROCESS | 11491 | Values: 1548.4 1566.9 1747.4 1865.9 1910.9 8268.6 8920.4 9006.2 9591.7 9595.8 9070.9 9341.4 9348.6 9349.3 9419.8 2476.8 2513.7 2517.3 2526.5 2539.2 2414.5 2501.1 2503.8 2526.0 2532.6 2474.8 2475.1 2499.7 2505.0 2523.3 2413.2 2425.5 2429.7 2455.8 2456.7 2316.5 2316.6 2325.2 2330.5 2472.5 9316.5 9493.3 9507.7 9513.5 9542.3 5451.5 5556.2 5588.6 5612.3 5614.6 3294.6 3386.3 3425.1 3441.4 3452.8 1111.2 1141.4 1153.4 1158.7 1212.6 12888.3 12939.4 12961.7 12995.7 13020.9 4729.5 4859.2 4878.6 4954.2 4969.4 10764.8 10803.3 10813.6 10872.1 10899.6 5527.4 5550.8 5554.8 5556.2 5556.7 4509.4 5008.2 5130.1 5133.3 5141.2 4311.5 4368.7 4398.5 4443.6 4507.3 1223.5 1227.3 1229.9 1235.2 1235.5 1042.5 1046.4 1051.4 1059.2 1087.2 6219.2 6335.4 6418.5 6462.5 6569.2 501.0 509.6 512.3 513.5 514.0 4416.1 4429.2 4454.8 4488.0 4506.3 11428.3 11772.5 11808.8 11822.3 11836.9 10855.1 11355.8 11362.4 11581.7 11707.3 8304.8 8473.4 8512.5 8514.4 8529.3 6484.1 6655.4 6691.8 6697.7 6753.3 10403.7 10808.6 10825.4 10828.8 10895.1 10523.4 10968.4 10975.4 10991.7 11102.3 8122.6 8219.4 8320.8 8389.4 8483.5 9272.5 9293.7 9352.9 9353.2 9362.1 8167.8 8239.2 8292.3 8393.1 8532.9 8184.1 8251.8 8253.3 8266.7 8289.0 7508.7 7512.9 7582.0 7658.2 7688.7 5595.4 5645.3 5706.1 5753.7 5865.6 5296.8 5438.8 5462.1 5488.3 5492.4 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | [#4] prototype.html Cycles:180 Average:5080.03 Median:5652.12 stddev:1510.60 (26.7%) stddev-sans-first:1472.27 05:39:46 INFO - PROCESS | 11491 | Values: 335.6 351.1 352.6 355.4 358.3 5704.7 5829.6 5878.9 5911.3 5922.8 5800.6 5801.9 5844.9 5872.3 5882.7 4567.2 4573.0 4573.7 4587.0 4591.8 4443.9 4510.0 4524.7 4554.4 4594.0 4468.3 4478.8 4481.0 4485.5 4489.7 4492.8 4493.0 4508.7 4542.8 4561.3 3884.7 3923.9 3946.6 3972.6 3991.6 5604.3 5626.6 5645.0 5659.2 5677.7 5171.4 5178.4 5187.2 5201.9 5231.2 5921.3 6095.7 6110.7 6112.6 6135.8 4357.0 4372.7 4380.3 4381.5 4383.3 6193.4 6357.9 6409.7 6419.2 6474.1 4443.2 4458.8 4475.8 4483.9 4488.4 4542.8 4545.6 4554.5 4554.7 4554.7 5182.7 5203.1 5210.5 5230.3 5244.5 5905.0 5915.6 6044.7 6176.5 6214.6 1545.7 1568.3 1571.5 1575.4 1582.8 948.6 979.4 1002.8 1013.4 1028.2 5208.8 5222.7 5283.3 5305.3 5322.5 5462.4 5598.8 5602.9 5610.2 5616.7 4937.9 5249.3 5260.3 5316.9 5333.5 2942.6 2984.8 2987.8 2990.8 2992.3 5758.0 5759.8 5790.3 5883.7 5889.9 5966.8 6383.2 6388.4 6398.4 6416.7 6589.2 6592.5 6603.3 6607.6 6612.4 6097.6 6291.4 6314.7 6316.7 6350.6 6233.8 6245.8 6286.5 6305.9 6343.7 6363.0 6454.5 6502.3 6599.7 6600.5 4465.6 4494.6 4511.0 4525.2 4543.1 6174.8 6361.3 6383.9 6447.3 6453.1 6103.2 6114.7 6163.9 6179.2 6202.7 6007.8 6154.7 6162.4 6189.2 6228.6 6035.5 6091.5 6100.5 6108.6 6117.0 6191.0 6200.9 6204.3 6227.7 6247.4 5768.5 5983.0 6012.5 6046.0 6051.6 05:39:46 INFO - PROCESS | 11491 | 05:39:46 INFO - PROCESS | 11491 | [#5] yui.html Cycles:180 Average:21931.49 Median:4182.20 stddev:30729.40 (734.8%) stddev-sans-first:30774.51 05:39:46 INFO - PROCESS | 11491 | Values: 766.9 802.7 821.4 880.8 912.1 16737.2 16924.5 17165.8 17844.6 17873.0 14245.6 14383.7 14392.5 14422.9 14450.4 725.2 877.8 881.9 894.2 899.9 891.3 893.0 893.0 893.5 895.0 957.4 957.4 957.8 959.2 960.5 949.3 949.4 949.6 949.6 949.7 691.5 695.9 696.8 697.8 698.4 16143.4 16194.3 16237.5 16280.3 16284.6 15079.9 15138.0 15618.4 15679.4 16399.8 13689.2 13707.3 13762.6 13863.2 13972.2 4088.4 4104.9 4136.9 4171.9 4192.5 92182.5 92784.5 92835.5 92884.1 93247.0 12187.8 12473.8 12584.4 12965.5 13090.9 23230.0 23496.9 23814.4 23999.8 24142.5 9131.1 9219.1 9257.1 9290.5 9394.4 5118.0 5137.4 5176.7 5227.9 5305.5 3690.5 3760.8 3787.9 3807.8 3811.0 627.3 641.5 650.7 667.8 700.7 650.4 658.6 701.9 775.8 846.6 10672.5 10861.0 10920.0 11124.9 11349.8 745.3 778.4 821.2 823.4 835.4 4617.0 4860.3 4874.5 4887.7 4940.0 73772.0 74228.6 84074.0 127200.4 127347.0 107059.5 107704.5 108083.0 111896.0 114000.4 60497.7 89292.0 90373.0 92134.0 93990.0 9498.0 9658.8 9834.3 9834.5 9835.6 53082.0 53441.7 53477.5 54518.7 56180.7 72834.6 74840.0 81284.0 81432.6 81892.0 12577.1 13037.2 13301.4 13402.8 13500.4 15761.2 15767.5 16197.7 16461.3 17008.2 16117.8 16210.3 16529.3 17053.9 17251.0 14856.5 15671.1 16266.2 16691.5 16946.0 14061.7 15249.2 15566.9 16080.6 16116.1 11208.7 11861.8 12967.2 13393.7 13640.4 12025.2 12643.4 12792.4 12873.9 13078.9 05:39:46 INFO - PROCESS | 11491 | -------- Summary: end -------- 05:39:46 INFO - PROCESS | 11491 | 05:39:47 INFO - TEST-INFO | 11491: exit 0 05:39:47 INFO - TEST-OK | dromaeo_css | took 1111000ms 05:39:47 INFO - TEST-START | kraken 05:39:47 INFO - operating with platform_type : linux_ 05:39:47 INFO - Initialising browser for kraken test... 05:39:47 INFO - TEST-INFO | started process 11755 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9SjRtB/profile http://localhost:46035/getInfo.html) 05:39:51 INFO - PROCESS | 11755 | __metrics Screen width/height:1600/1200 05:39:51 INFO - PROCESS | 11755 | colorDepth:24 05:39:51 INFO - PROCESS | 11755 | Browser inner width/height: 1024/697 05:39:51 INFO - PROCESS | 11755 | __metrics 05:39:52 ERROR - PROCESS | 11755 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:39:53 INFO - TEST-INFO | 11755: exit 0 05:39:53 INFO - Browser initialized. 05:39:53 INFO - Running cycle 1/1 for kraken test... 05:39:53 INFO - TEST-INFO | started process 11879 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9SjRtB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:40:04 INFO - PROCESS | 11879 | RSS: Main: 153292800 05:40:04 INFO - PROCESS | 11879 | 05:40:07 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46035/tests/kraken/driver.html?testName=audio-beat-detection) 05:40:08 INFO - PROCESS | 11879 | RSS: Main: 158666752 05:40:08 INFO - PROCESS | 11879 | 05:40:10 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46035/tests/kraken/driver.html?testName=audio-dft) 05:40:11 INFO - PROCESS | 11879 | RSS: Main: 157016064 05:40:11 INFO - PROCESS | 11879 | 05:40:14 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46035/tests/kraken/driver.html?testName=audio-fft) 05:40:14 INFO - PROCESS | 11879 | RSS: Main: 157921280 05:40:14 INFO - PROCESS | 11879 | 05:40:17 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46035/tests/kraken/driver.html?testName=audio-oscillator) 05:40:17 INFO - PROCESS | 11879 | RSS: Main: 157376512 05:40:17 INFO - PROCESS | 11879 | 05:40:19 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46035/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:40:20 INFO - PROCESS | 11879 | RSS: Main: 158023680 05:40:20 INFO - PROCESS | 11879 | 05:40:23 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46035/tests/kraken/driver.html?testName=imaging-darkroom) 05:40:24 INFO - PROCESS | 11879 | RSS: Main: 158109696 05:40:24 INFO - PROCESS | 11879 | 05:40:27 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46035/tests/kraken/driver.html?testName=imaging-desaturate) 05:40:28 INFO - PROCESS | 11879 | RSS: Main: 158769152 05:40:28 INFO - PROCESS | 11879 | 05:40:31 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46035/tests/kraken/driver.html?testName=json-parse-financial) 05:40:31 INFO - PROCESS | 11879 | RSS: Main: 158392320 05:40:31 INFO - PROCESS | 11879 | 05:40:33 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46035/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:40:33 INFO - PROCESS | 11879 | RSS: Main: 157736960 05:40:33 INFO - PROCESS | 11879 | 05:40:35 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:40:36 INFO - PROCESS | 11879 | RSS: Main: 157716480 05:40:36 INFO - PROCESS | 11879 | 05:40:38 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:40:38 INFO - PROCESS | 11879 | RSS: Main: 157597696 05:40:38 INFO - PROCESS | 11879 | 05:40:41 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:40:42 INFO - PROCESS | 11879 | RSS: Main: 158101504 05:40:42 INFO - PROCESS | 11879 | 05:40:44 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:40:45 INFO - PROCESS | 11879 | RSS: Main: 159072256 05:40:45 INFO - PROCESS | 11879 | 05:40:46 INFO - PROCESS | 11879 | Cycle 1(1): loaded http://localhost:46035/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46035/tests/kraken/driver.html?testName=ai-astar) 05:40:47 INFO - PROCESS | 11879 | RSS: Main: 160575488 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | __start_tp_report 05:40:47 INFO - PROCESS | 11879 | _x_x_mozilla_page_load 05:40:47 INFO - PROCESS | 11879 | _x_x_mozilla_page_load_details 05:40:47 INFO - PROCESS | 11879 | |i|pagename|runs| 05:40:47 INFO - PROCESS | 11879 | |0;ai-astar;102;109;113;105;111;113;112;112;113;121 05:40:47 INFO - PROCESS | 11879 | |1;audio-beat-detection;139;117;138;179;118;149;140;120;170;115 05:40:47 INFO - PROCESS | 11879 | |2;audio-dft;162;162;165;162;166;161;162;163;161;163 05:40:47 INFO - PROCESS | 11879 | |3;audio-fft;75;75;75;182;80;75;75;75;173;75 05:40:47 INFO - PROCESS | 11879 | |4;audio-oscillator;83;83;138;85;84;83;144;83;83;138 05:40:47 INFO - PROCESS | 11879 | |5;imaging-gaussian-blur;117;117;117;118;117;118;118;117;117;117 05:40:47 INFO - PROCESS | 11879 | |6;imaging-darkroom;147;147;147;147;146;147;148;148;147;147 05:40:47 INFO - PROCESS | 11879 | |7;imaging-desaturate;96;96;96;95;95;99;95;96;96;95 05:40:47 INFO - PROCESS | 11879 | |8;json-parse-financial;54;52;52;51;53;54;52;55;51;51 05:40:47 INFO - PROCESS | 11879 | |9;json-stringify-tinderbox;61;60;59;61;62;59;61;61;61;60 05:40:47 INFO - PROCESS | 11879 | |10;stanford-crypto-aes;78;72;75;73;72;72;72;72;71;72 05:40:47 INFO - PROCESS | 11879 | |11;stanford-crypto-ccm;121;117;129;117;121;130;117;115;147;122 05:40:47 INFO - PROCESS | 11879 | |12;stanford-crypto-pbkdf2;152;151;154;152;151;150;153;152;152;152 05:40:47 INFO - PROCESS | 11879 | |13;stanford-crypto-sha256-iterative;62;62;63;62;61;60;62;61;62;61 05:40:47 INFO - PROCESS | 11879 | __end_tp_report 05:40:47 INFO - PROCESS | 11879 | __start_cc_report 05:40:47 INFO - PROCESS | 11879 | _x_x_mozilla_cycle_collect,897 05:40:47 INFO - PROCESS | 11879 | __end_cc_report 05:40:47 INFO - PROCESS | 11879 | __startTimestamp1473684047212__endTimestamp 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | ------- Summary: start ------- 05:40:47 INFO - PROCESS | 11879 | Number of tests: 14 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.50 stddev:5.11 (4.5%) stddev-sans-first:4.23 05:40:47 INFO - PROCESS | 11879 | Values: 102.0 109.0 113.0 105.0 111.0 113.0 112.0 112.0 113.0 121.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:139.50 stddev:22.42 (16.1%) stddev-sans-first:23.78 05:40:47 INFO - PROCESS | 11879 | Values: 139.0 117.0 138.0 179.0 118.0 149.0 140.0 120.0 170.0 115.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#2] audio-dft Cycles:10 Average:162.70 Median:162.50 stddev:1.64 (1.0%) stddev-sans-first:1.72 05:40:47 INFO - PROCESS | 11879 | Values: 162.0 162.0 165.0 162.0 166.0 161.0 162.0 163.0 161.0 163.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#3] audio-fft Cycles:10 Average:96.00 Median:75.00 stddev:43.03 (57.4%) stddev-sans-first:44.97 05:40:47 INFO - PROCESS | 11879 | Values: 75.0 75.0 75.0 182.0 80.0 75.0 75.0 75.0 173.0 75.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#4] audio-oscillator Cycles:10 Average:100.40 Median:83.00 stddev:27.38 (33.0%) stddev-sans-first:28.31 05:40:47 INFO - PROCESS | 11879 | Values: 83.0 83.0 138.0 85.0 84.0 83.0 144.0 83.0 83.0 138.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 05:40:47 INFO - PROCESS | 11879 | Values: 117.0 117.0 117.0 118.0 117.0 118.0 118.0 117.0 117.0 117.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:40:47 INFO - PROCESS | 11879 | Values: 147.0 147.0 147.0 147.0 146.0 147.0 148.0 148.0 147.0 147.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:1.20 (1.2%) stddev-sans-first:1.27 05:40:47 INFO - PROCESS | 11879 | Values: 96.0 96.0 96.0 95.0 95.0 99.0 95.0 96.0 96.0 95.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.43 (2.7%) stddev-sans-first:1.41 05:40:47 INFO - PROCESS | 11879 | Values: 54.0 52.0 52.0 51.0 53.0 54.0 52.0 55.0 51.0 51.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 05:40:47 INFO - PROCESS | 11879 | Values: 61.0 60.0 59.0 61.0 62.0 59.0 61.0 61.0 61.0 60.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#10] stanford-crypto-aes Cycles:10 Average:72.90 Median:72.00 stddev:2.08 (2.9%) stddev-sans-first:1.12 05:40:47 INFO - PROCESS | 11879 | Values: 78.0 72.0 75.0 73.0 72.0 72.0 72.0 72.0 71.0 72.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:121.50 stddev:9.65 (7.9%) stddev-sans-first:10.19 05:40:47 INFO - PROCESS | 11879 | Values: 121.0 117.0 129.0 117.0 121.0 130.0 117.0 115.0 147.0 122.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.90 Median:152.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:40:47 INFO - PROCESS | 11879 | Values: 152.0 151.0 154.0 152.0 151.0 150.0 153.0 152.0 152.0 152.0 05:40:47 INFO - PROCESS | 11879 | 05:40:47 INFO - PROCESS | 11879 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.60 Median:62.00 stddev:0.84 (1.4%) stddev-sans-first:0.88 05:40:47 INFO - PROCESS | 11879 | Values: 62.0 62.0 63.0 62.0 61.0 60.0 62.0 61.0 62.0 61.0 05:40:47 INFO - PROCESS | 11879 | -------- Summary: end -------- 05:40:47 INFO - PROCESS | 11879 | 05:40:48 INFO - TEST-INFO | 11879: exit 0 05:40:48 INFO - TEST-OK | kraken | took 60842ms 05:40:48 INFO - SUITE-END | took 1171s 05:40:48 INFO - Completed test suite (00:19:32) 05:40:48 INFO - javascript benchmark 05:40:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7793.981825182489, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.6712328767117, 3322.1196382958537, 3328.384248913951, 3329.650386709396, 3349.2129349602856, 5695.886082278352, 5749.655020698756, 5778.191053252548, 5790.189373487715, 5817.941820581793, 5649.661020338773, 5662.801801936933, 5697.316322041351, 5707.800226992056, 5717.571182161334, 5163.457836927116, 5202.08963431398, 5222.92165617516, 5260.184671375943, 5314.229436731672, 5182.585393168538, 5214.426413094557, 5231.581473482112, 5238.633295669305, 5276.736163191844, 5107.591392688594, 5116.539511443969, 5125.743712814363, 5126.436092029874, 5346.064438723227, 5123.462036486171, 5132.307138536287, 5135.794568217267, 5151.330327057477, 5173.560247378978, 4974.651774375759, 5033.622478314104, 5034.270030845543, 5047.242913562958, 5091.439941606421, 5546.389897111315, 5610.074337734269, 5623.212750214973, 5637.577181711388, 5675.091985282335, 6122.265328160604, 6157.2919114301985, 6161.229846269216, 6177.62934223948, 6202.689865506707, 6198.938010619926, 6207.81376558704, 6209.59637623553, 6228.781992630236, 6236.064590311445, 5667.716614169275, 5672.432756724335, 5686.289213848269, 5715.828525144252, 5750.741216645261, 6330.113784070234, 6336.778212762531, 6349.777757778455, 6351.301356850743, 6359.427651511386, 6067.423594758491, 6068.817935461943, 6073.0, 6078.787242446493, 6083.270007599116, 5952.583319167703, 5998.0, 6032.457078862835, 6037.034074548052, 6079.726412311456, 6014.097885317237, 6058.273007239071, 6061.121137435135, 6066.393360663898, 6091.0, 6023.307319658208, 6037.09443583466, 6054.636721796706, 6065.029595264737, 6078.908816367758, 4619.1685496610935, 4622.19111655459, 4636.744979026119, 4655.557722016403, 4671.813127474863, 4196.370544418301, 4199.916001680011, 4200.6219440250525, 4200.915981680349, 4233.301505251599, 5922.1412895130825, 5935.139404786281, 5937.614055086406, 5942.465178133989, 5952.85117872048, 5976.850578735566, 6110.144579758838, 6117.9492029047315, 6129.264488261437, 6189.133521307021, 5942.494887934487, 5956.434138756811, 5968.4926781224085, 5972.910406343908, 5988.401159884064, 5334.51989320963, 5354.491323324278, 5355.22349259363, 5359.14253719403, 5383.461653834664, 6149.907751383733, 6262.2172228471445, 6306.0, 6307.3377295383325, 6465.967670161714, 6189.04069869171, 6196.287426945959, 6202.7829025985675, 6204.162438070803, 6214.471769899428, 6395.840103997437, 6397.840053998687, 6400.455961243347, 6407.295197528175, 6416.486681065597, 5962.016267315845, 6041.909371359346, 6048.002079656983, 6068.969655151696, 6110.602810817282, 6251.031090181029, 6272.153259309935, 6290.402411771058, 6299.559030867796, 6301.527385446201, 6088.269407671108, 6120.7857724981195, 6127.050307202391, 6134.601250918676, 6136.478399336106, 5810.273715785527, 5892.1161825726485, 5911.704414779158, 5915.615484993462, 6117.663528505975, 5925.318588362394, 5935.961206788708, 5942.692607626315, 5946.07835785454, 5969.70151492415, 5940.019896716994, 5956.0, 5984.341722410444, 5995.370486098896, 6016.338202797601, 5698.8290351289525, 5806.303243610793, 5857.26784151981, 5868.149118377726, 5869.73586188614], "value": 5653.760433582474, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.6869359239605, 640.1517988665018, 660.6465540935599, 689.6344937183295, 729.0667945030357, 41857.79071104648, 46440.53559464405, 46675.29987050197, 46729.76635116824, 47040.76479617606, 46591.53408465914, 47142.057158856805, 47230.291545626846, 47247.29129063067, 47355.76322118384, 9031.142041506062, 9153.816923661523, 9184.173424391804, 9219.170274675278, 9224.907750922506, 9169.0, 9229.723108306727, 9241.214496767783, 9247.768805779875, 9248.491332976684, 9277.072292770736, 9304.674336398228, 9304.906950930472, 9311.534423278808, 9362.438253704799, 9253.583588738524, 9291.117343852356, 9298.86051709225, 9300.30247731416, 9300.906990930072, 9083.81832363349, 9142.177204051668, 9191.494467804268, 9200.539973001323, 9207.40151890125, 44644.553554464364, 44822.0, 45012.77493612544, 45050.32424513635, 45247.77376113131, 3192.8722851085927, 3590.2819436112622, 3709.7959612221316, 3832.5209348831395, 3851.691864650821, 33492.49761253583, 33586.66413335884, 33852.6614733852, 33925.83037084799, 33928.83035584831, 4349.195398851222, 4380.08018316154, 4500.8649740507835, 4536.319552067183, 4580.725156490621, 397502.0, 397722.0, 402530.0, 404650.0, 407733.96133019443, 68326.65836670884, 68925.65537172282, 68934.0, 68971.0, 69030.30969690239, 38759.61240387616, 39198.020049498424, 39199.80400097981, 70378.0, 70614.29385706077, 18573.90713046426, 18989.0, 19112.2355105797, 19127.13927873249, 19264.32574859873, 14599.41602335916, 14614.196219207834, 14682.265886705622, 14690.19203943772, 14720.19038952868, 20603.690944635844, 20937.685934710993, 20977.475563111046, 21008.789912100685, 21292.828894410955, 7546.509476883985, 7611.543307401574, 7761.573113478813, 7822.374210063182, 8014.959925200336, 1031.1802117316602, 1086.2124959404528, 1097.9066779323846, 1114.7213196700825, 1236.9010479161645, 33302.50046249308, 33321.666783332344, 33575.0, 33655.83172084173, 33829.154271143416, 1169.4503583315827, 1177.8083429746096, 1179.687382843547, 1214.5688280660415, 1241.2986662535639, 17110.401135960183, 17792.46622601324, 17847.37534186297, 17878.731819022723, 17981.460556183334, 1122459.0, 1143311.0, 1144455.0, 1145802.0, 1149134.5086549027, 63329.36670633327, 63626.36373636297, 63705.0, 64079.35920640734, 64353.678231608545, 1468.4126349460087, 1483.7403454395437, 1489.6275931017246, 1500.5423345879612, 1522.0335087219696, 34926.0, 35298.11754706153, 35616.287674245854, 35714.821425893744, 36075.0, 57071.0, 57373.13940290816, 57774.0, 57816.0, 57927.4207257922, 525099.0, 536245.0, 536265.0, 536431.0, 537838.0, 30992.605332759627, 31527.21181970469, 31650.366992660478, 31768.0, 33157.66842331546, 53120.734396327774, 53148.2027769576, 53802.73098634482, 54005.729971351466, 54575.727121364136, 45242.773786130856, 45857.16571337243, 46927.53072469368, 47464.52535474739, 48026.27960580524, 46649.53350466452, 46669.76665116653, 46715.79844085867, 46912.7654361726, 46937.2959405616, 44138.11723765443, 45658.0, 45777.54222457733, 45794.771026144655, 46799.298010529186, 39246.803765980985, 39609.40585891157, 40302.1939561213, 40561.0, 40827.7958610205, 30466.390672186877, 30480.542791857697, 30665.54001689932, 30861.691383085883, 30924.536131958488], "value": 22217.138894681157, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.8270326462885, 417.9540250572438, 425.69988158348366, 430.5586773557105, 440.99559004409946, 19252.326168584103, 20762.792372076274, 20776.06507707153, 21169.470763230926, 21680.891595542, 20310.898445507788, 20540.691889621667, 20569.28007519737, 20853.687194692055, 21027.053782579784, 5975.253093363341, 6197.0, 6198.907016394758, 6200.534959878005, 6205.813825585217, 6039.365866584011, 6059.818205453844, 6072.149899014142, 6094.664793436359, 6106.603070800384, 5881.7353219105025, 6020.698965051752, 6040.305364883051, 6041.939580604182, 6042.728077236513, 5545.140503221987, 5707.885842283152, 5825.941740582624, 5875.853103672443, 5955.493783028448, 5618.269624948731, 5647.152927060933, 5668.234788303567, 5752.252207213036, 5781.942180578182, 14069.64825879351, 14293.142411455243, 14296.285185740775, 22464.764437955906, 24154.7584524156, 2769.1969328894597, 2785.8189217700783, 2872.626558547396, 3128.71841534263, 3167.0657156138996, 11273.26723762961, 12471.251724896536, 12777.936110319482, 15332.15673137977, 15583.766243506356, 2010.577778666482, 2032.674772036482, 2066.4627196928755, 2090.832733381326, 2124.0654112190587, 89706.0, 89957.55021224852, 91231.54384228103, 91671.54164229135, 91761.54119229427, 11499.3675347855, 11656.650300491, 11772.587959421378, 11795.115366347558, 11954.940225298991, 16684.916575417046, 16735.07957062349, 16767.748483772753, 16780.0, 16782.66434671324, 8409.663613455394, 8518.105598912149, 8709.782255443537, 8820.162084601952, 8976.910230897738, 8432.156784321518, 8469.407141500036, 8492.490450572861, 8593.871091933626, 8641.913580864237, 4955.776990035385, 4997.800087996512, 5007.84976450707, 5058.54473097423, 5152.845414637567, 2040.3776848061186, 2160.6867004284604, 2182.4871155278497, 2186.2348178137527, 2201.76881427451, 1024.0, 1024.902634249745, 1053.4153544782566, 1073.608133031438, 1113.0427832064418, 19925.900370498344, 19951.20195192176, 20142.69785953212, 20345.796542034685, 20480.59038819215, 1033.7932413517326, 1053.0469924718143, 1064.21248276275, 1072.51736718477, 1104.8563686720674, 10451.268411211142, 11447.19869609136, 11471.885281147082, 11513.078953683853, 11544.307341559534, 513087.0, 514891.0, 518478.0, 524141.0, 525119.0, 94945.0, 95701.52149239209, 95762.0, 100349.0, 102655.48672256591, 1072.7050061233224, 1075.3386667199616, 1107.2968664897849, 1107.6953837694698, 1116.4752566293828, 18204.72692909581, 18744.531386714894, 18885.43343699691, 19262.518437038627, 19447.0, 42134.0, 42459.36310955277, 44465.99903004308, 45490.77254613706, 46771.532284676716, 58412.70793646004, 58930.70534647299, 64422.0, 64861.67569162124, 65382.67308663426, 8414.158584141536, 8901.287896968357, 8922.063183365653, 8959.148880856308, 9126.726198214064, 10493.632722854663, 11228.943855280671, 13267.336633168496, 13742.244176569984, 14049.789253161007, 11800.409979501163, 11940.820887686517, 12119.151659383759, 12302.81545776832, 12452.128351015705, 11822.998625580172, 11855.58505452305, 11908.166428349932, 12042.337671428155, 12274.079444041554, 11847.76304473923, 12030.458629361874, 12047.51809927593, 12157.635270941886, 12184.756304873676, 8490.23587877094, 8602.05377408497, 8616.396852220285, 8723.738287851374, 9004.459732416077, 11285.0, 12004.2197257178, 12172.208806427554, 12185.268883866996, 12224.0], "value": 9768.528304755251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1548.3806477409034, 1566.9059856408617, 1747.3884140550801, 1865.9067046647672, 1910.8853468791865, 8268.627911743972, 8920.420172688771, 9006.189442950134, 9591.66429174979, 9595.808083838328, 9070.863937040933, 9341.392809467397, 9348.579313930872, 9349.345545811795, 9419.764505887339, 2476.752324767522, 2513.710923243823, 2517.345490172559, 2526.519961207374, 2539.2255362114547, 2414.541237164942, 2501.062101711858, 2503.7746602805746, 2525.9621105683336, 2532.556802559554, 2474.7648973347505, 2475.121331927167, 2499.687539057618, 2505.0105208442656, 2523.3060908250195, 2413.2398294537165, 2425.454272788626, 2429.7327293997646, 2455.7667021632915, 2456.6683497727936, 2316.5366926614743, 2316.6061769499056, 2325.2442956039185, 2330.5222429401933, 2472.4807790364043, 9316.534173291377, 9493.288003399774, 9507.714768556954, 9513.476758778264, 9542.33203675743, 5451.454854514556, 5556.222128901957, 5588.608797384185, 5612.270404847384, 5614.578906582023, 3294.5717056782464, 3386.339663765567, 3425.1265927188656, 3441.4493681011168, 3452.8446219920165, 1111.199856232908, 1141.4064686363045, 1153.3771763247837, 1158.7450760832603, 1212.630147804928, 12888.291143987077, 12939.417726202344, 12961.740765184644, 12995.740085198338, 13020.86979130206, 4729.456112547033, 4859.222524396079, 4878.609711223165, 4954.1825598775795, 4969.403671559436, 10764.838527422095, 10803.297785643908, 10813.62152324665, 10872.130229581615, 10899.618513352154, 5527.39198688144, 5550.8057217997175, 5554.833354999357, 5556.249906262684, 5556.749946252428, 4509.368688383629, 5008.173651347487, 5130.102232109366, 5133.25567792668, 5141.151709967889, 4311.482622085307, 4368.716033457815, 4398.47218333802, 4443.577860103285, 4507.256302710081, 1223.473906220334, 1227.3495047624774, 1229.9139060265695, 1235.1600911380167, 1235.5366737473448, 1042.5048102151568, 1046.40877903984, 1051.374432212832, 1059.1685526861377, 1087.1900434176432, 6219.222597175353, 6335.3981371769605, 6418.454431373386, 6462.450691691259, 6569.244536878225, 500.9855043536867, 509.55923126495316, 512.3160580624907, 513.4839486316303, 514.0284861611557, 4416.094700586372, 4429.15845989261, 4454.7549884756, 4488.0, 4506.278995360726, 11428.314301141958, 11772.470238839272, 11808.822867656992, 11822.34977076269, 11836.88163118375, 10855.131589472981, 11355.829662555068, 11362.375069371099, 11581.652550423332, 11707.297562146128, 8304.750857474286, 8473.449225800427, 8512.489250644981, 8514.361422893307, 8529.275011623957, 6484.092227088117, 6655.434288085567, 6691.8327041822395, 6697.665116744241, 6753.3246675332075, 10403.687889363331, 10808.621698240524, 10825.404602746925, 10828.837567436653, 10895.07391871668, 10523.421211833116, 10968.396738179477, 10975.396353200651, 10991.670249892515, 11102.2783519071, 8122.55325957078, 8219.383546234176, 8320.833583328178, 8389.412741108064, 8483.533405662747, 9272.490013049077, 9293.67472138472, 9352.906470935204, 9353.204977576983, 9362.063793620584, 8167.754967350989, 8239.217274358927, 8292.33661307082, 8393.076761556345, 8532.914670853212, 8184.140665230065, 8251.793705157179, 8253.257206851413, 8266.710665126691, 8289.0, 7508.699652013858, 7512.924870751441, 7582.0, 7658.195889431529, 7688.730894418669, 5595.440455954372, 5645.266115404971, 5706.115552089433, 5753.683547404766, 5865.589408741347, 5296.814611488579, 5438.809641662523, 5462.126059830408, 5488.286522752017, 5492.395836458067], "value": 4847.236171771884, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.60063524405956, 351.1274482909968, 352.6103655460716, 355.44195612887745, 358.27806968957555, 5704.743286552105, 5829.621074630146, 5878.854218210355, 5911.290645122592, 5922.792702255417, 5800.593958422912, 5801.854953626162, 5844.912326315109, 5872.324682661484, 5882.735276912538, 4567.1550763108935, 4573.0, 4573.656975726825, 4586.990862010369, 4591.77041147943, 4443.911121777563, 4510.0, 4524.660650451213, 4554.385158003677, 4593.955689718076, 4468.262736648449, 4478.776061196943, 4480.977595112036, 4485.506594274643, 4489.685721999462, 4492.775361231942, 4492.955070449303, 4508.706934049309, 4542.818287268506, 4561.270196768534, 3884.7474914130776, 3923.8822835314986, 3946.565877753445, 3972.5828787977134, 3991.6207960243732, 5604.346717718242, 5626.634268772557, 5645.0, 5659.236003139565, 5677.716114194273, 5171.405288391846, 5178.394697643705, 5187.221916712486, 5201.947980520222, 5231.163013917755, 5921.259842519685, 6095.664738439384, 6110.663913484756, 6112.572119951606, 6135.78524751636, 4356.978215108904, 4372.650187984986, 4380.255356589388, 4381.474223093217, 4383.320585309282, 6193.411625895577, 6357.872842543169, 6409.743610255584, 6419.197600299963, 6474.12599299098, 4443.244648409778, 4458.77706114693, 4475.769163480004, 4483.932741008887, 4488.4165058542185, 4542.750148741785, 4545.568171023748, 4554.521775213621, 4554.656376368979, 4554.726716397026, 5182.663126896739, 5203.115470370046, 5210.505002024802, 5230.34620672416, 5244.475552444725, 5904.970475147683, 5915.615484993462, 6044.697765111727, 6176.505879529627, 6214.601714614176, 1545.65995480994, 1568.2629164292764, 1571.4657016614178, 1575.3777257983033, 1582.7970742235984, 948.6300342866288, 979.4025644356936, 1002.7844013536978, 1013.3735354755511, 1028.228828378716, 5208.765605547759, 5222.694326418396, 5283.286756287929, 5305.31030965972, 5322.494363035506, 5462.371827239918, 5598.804041858515, 5602.915956260659, 5610.18652295423, 5616.719164041863, 4937.851864444073, 5249.343832020997, 5260.342457192851, 5316.893662126736, 5333.5199832015305, 2942.558616207586, 2984.7612191024664, 2987.805792623516, 2990.7607391408637, 2992.2968102495897, 5757.992351338499, 5759.832092349191, 5790.305163380422, 5883.676397798162, 5889.94110058894, 5966.791162309299, 6383.202099737533, 6388.425041746265, 6398.424141827258, 6416.743330266737, 6589.209294884645, 6592.5055620828625, 6603.273639899608, 6607.6365799880505, 6612.437942774918, 6097.634141951497, 6291.370862913672, 6314.652694101685, 6316.6841657916, 6350.618962862243, 6233.787005612942, 6245.812625621239, 6286.465650419766, 6305.936940630719, 6343.651099189405, 6363.0, 6454.548181627241, 6502.252240992158, 6599.703013364314, 6600.53796234259, 4465.620422264144, 4494.595486406109, 4510.954890451054, 4525.185466616041, 4543.136804007228, 6174.845628859314, 6361.332060133805, 6383.904241436289, 6447.258565265054, 6453.096566480603, 6103.176071230505, 6114.694265286629, 6163.9383606163365, 6179.16581261524, 6202.72087756043, 6007.791572930077, 6154.676744499954, 6162.352952940056, 6189.164462797645, 6228.626282423069, 6035.521297282194, 6091.451769340781, 6100.54245931548, 6108.5723999319625, 6116.969415152896, 6191.0, 6200.937990620036, 6204.348543402878, 6227.657478838707, 6247.437730604267, 5768.480836724715, 5982.970085149546, 6012.488938440281, 6045.969770151121, 6051.576389652682], "value": 4593.6363359673815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.8581312457196, 802.690963978868, 821.4414198345127, 880.7754022724205, 912.1334732004595, 16737.246823892925, 16924.49226523203, 17165.82834171658, 17844.55388615285, 17873.0, 14245.643858903533, 14383.712325753479, 14392.49626263081, 14422.927885360585, 14450.421983120663, 725.2276325713125, 877.8200468903859, 881.889763779526, 894.239896088323, 899.8785164002874, 891.2780647675371, 892.977675558113, 893.0355216366341, 893.4817805672694, 894.9686760963367, 957.3968399908048, 957.4351132831628, 957.7701351675618, 959.2086528613886, 960.4909398019026, 949.3449519831329, 949.3686698345592, 949.6296444386695, 949.6486300068949, 949.6771097826772, 691.5401258163315, 695.9234484206734, 696.8292768271806, 697.8290318871858, 698.3958875572594, 16143.434979775651, 16194.271257793309, 16237.51287461378, 16280.26738796757, 16284.592885177846, 15079.924600377037, 15138.0, 15618.375264989387, 15679.372825086983, 16399.754003689955, 13689.178649281075, 13707.314634268247, 13762.587122386345, 13863.168209907439, 13972.161670299713, 4088.407180958774, 4104.91790164195, 4136.875893723193, 4171.937420938658, 4192.4549808525, 92182.53908730413, 92784.53607731986, 92835.53582232114, 92884.07115928821, 93247.0, 12187.817182742267, 12473.75052498945, 12584.433700483318, 12965.481380744854, 13090.934545327213, 23230.0, 23496.882515587655, 23814.404639884142, 23999.7600024001, 24142.51714965691, 9131.091147573545, 9219.124183202584, 9257.120573545502, 9290.489023103793, 9394.389364691273, 5118.0, 5137.40919794221, 5176.7411629418375, 5227.895442091137, 5305.522502974673, 3690.520232369775, 3760.7555508891833, 3787.886363409047, 3807.828647710804, 3811.0, 627.3412916437723, 641.4515589171285, 650.72344253692, 667.8163505036154, 700.7092056796372, 650.3756393861945, 658.6212927566669, 701.894715792625, 775.8254392761696, 846.6190214403543, 10672.466376681135, 10861.0, 10920.017198452177, 11124.888751112385, 11349.829752553718, 745.2956955676942, 778.3928535742131, 821.2444551013082, 823.4277177361796, 835.4277320035795, 4617.0, 4860.295257187688, 4874.488178741252, 4887.65786394956, 4939.950600493949, 73772.0, 74228.62885685537, 84074.0, 127200.36399817941, 127347.0, 107059.46470267755, 107704.46147769211, 108083.0, 111896.0, 114000.42999784948, 60497.69751151216, 89292.0, 90372.99999999869, 92134.0, 93990.0, 9497.952510237405, 9658.7585310365, 9834.311598188058, 9834.459104749307, 9835.557399917165, 53082.0, 53441.732791335795, 53477.465225347245, 54518.72740636271, 56180.71909640425, 72834.63582682052, 74840.0, 81284.0, 81432.59283703781, 81892.0, 12577.056720745797, 13037.217766934014, 13301.401436935168, 13402.798958015443, 13500.392482338122, 15761.211939403212, 15767.526974190792, 16197.67604647924, 16461.25924333432, 17008.234629441456, 16117.838821611635, 16210.270537826062, 16529.33882644681, 17053.91473042627, 17251.0, 14856.480023199136, 15671.138087405303, 16266.186690665656, 16691.49925502237, 16946.0, 14061.718765624426, 15249.16129612882, 15566.922165389553, 16080.597985049999, 16116.113613751357, 11208.66374008781, 11861.76276474483, 12967.22196668203, 13393.66515837112, 13640.386182621607, 12025.218360806519, 12643.367831608199, 12792.424340904496, 12873.871261287268, 13078.869211307765], "value": 8203.653561305988, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1492.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 109.0, 113.0, 105.0, 111.0, 113.0, 112.0, 112.0, 113.0, 121.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 117.0, 138.0, 179.0, 118.0, 149.0, 140.0, 120.0, 170.0, 115.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 165.0, 162.0, 166.0, 161.0, 162.0, 163.0, 161.0, 163.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 182.0, 80.0, 75.0, 75.0, 75.0, 173.0, 75.0], "value": 96.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 138.0, 85.0, 84.0, 83.0, 144.0, 83.0, 83.0, 138.0], "value": 100.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 148.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 99.0, 95.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 51.0, 53.0, 54.0, 52.0, 55.0, 51.0, 51.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 61.0, 62.0, 59.0, 61.0, 61.0, 61.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 75.0, 73.0, 72.0, 72.0, 72.0, 72.0, 71.0, 72.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 117.0, 129.0, 117.0, 121.0, 130.0, 117.0, 115.0, 147.0, 122.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 154.0, 152.0, 151.0, 150.0, 153.0, 152.0, 152.0, 152.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 61.0, 60.0, 62.0, 61.0, 62.0, 61.0], "value": 61.6, "unit": "score"}]}]} 05:40:48 INFO - Return code: 0 05:40:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:40:48 ERROR - # TBPL SUCCESS # 05:40:48 INFO - Running post-action listener: _resource_record_post_action 05:40:48 INFO - [mozharness: 2016-09-12 12:40:48.496102Z] Finished run-tests step (success) 05:40:48 INFO - Running post-run listener: _resource_record_post_run 05:40:48 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 262144; Write bytes: 328343552; Read time: 20; Write time: 251176 05:40:48 INFO - TinderboxPrint: CPU usage
13.3% 05:40:48 INFO - TinderboxPrint: I/O read bytes / time
262,144 / 20 05:40:48 INFO - TinderboxPrint: I/O write bytes / time
328,343,552 / 251,176 05:40:48 INFO - TinderboxPrint: CPU idle
8,222.0 (86.7%) 05:40:48 INFO - TinderboxPrint: CPU user
1,202.5 (12.7%) 05:40:48 INFO - TinderboxPrint: Swap in / out
0 / 0 05:40:48 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 77180928; Read time: 0; Write time: 80344 05:40:48 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248651776; Read time: 20; Write time: 170672 05:40:49 INFO - Running post-run listener: _upload_blobber_files 05:40:49 INFO - Blob upload gear active. 05:40:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:40:49 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:49 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:49 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:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:40:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:40:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:40:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:50 INFO - (blobuploader) - INFO - Done attempting. 05:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:40:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:55 INFO - (blobuploader) - INFO - Done attempting. 05:40:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:40:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:40:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:40:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:40:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:40:55 INFO - (blobuploader) - INFO - Done attempting. 05:40:55 INFO - (blobuploader) - INFO - Iteration through files over. 05:40:55 INFO - Return code: 0 05:40:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:40:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:40:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04d84a7d4613cb3bf921ab13d33f46d1f095a796f76497f543c4fda897b109fc50c20469783077419444870d478d0977568296fdae34943a02493d9cd3d9734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7ef16d6852af4dba442f37a9bd090c8ceecb3906a15c71686b10b65c6aef47f275c5ded9d3183b8943cb090b10f3069c678b8e572324038c12cf386ea09905e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3421c6ccccc5396a7b76f8f5b4c1014b957c8a2ed239096636e63f81871fdc190511427266a33844b92dbb79d8f99ad033c0645d2e6b4f4a59c7f31595d3220"} 05:40:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:40:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:40:55 INFO - Contents: 05:40:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04d84a7d4613cb3bf921ab13d33f46d1f095a796f76497f543c4fda897b109fc50c20469783077419444870d478d0977568296fdae34943a02493d9cd3d9734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7ef16d6852af4dba442f37a9bd090c8ceecb3906a15c71686b10b65c6aef47f275c5ded9d3183b8943cb090b10f3069c678b8e572324038c12cf386ea09905e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3421c6ccccc5396a7b76f8f5b4c1014b957c8a2ed239096636e63f81871fdc190511427266a33844b92dbb79d8f99ad033c0645d2e6b4f4a59c7f31595d3220"} 05:40:55 INFO - Running post-run listener: copy_logs_to_upload_dir 05:40:55 INFO - Copying logs to upload dir... 05:40:55 INFO - mkdir: /builds/slave/test/build/upload/logs 05:40:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.120173 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-12 05:40:55.666488) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:55.670292) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04d84a7d4613cb3bf921ab13d33f46d1f095a796f76497f543c4fda897b109fc50c20469783077419444870d478d0977568296fdae34943a02493d9cd3d9734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7ef16d6852af4dba442f37a9bd090c8ceecb3906a15c71686b10b65c6aef47f275c5ded9d3183b8943cb090b10f3069c678b8e572324038c12cf386ea09905e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3421c6ccccc5396a7b76f8f5b4c1014b957c8a2ed239096636e63f81871fdc190511427266a33844b92dbb79d8f99ad033c0645d2e6b4f4a59c7f31595d3220"} 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.011257 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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04d84a7d4613cb3bf921ab13d33f46d1f095a796f76497f543c4fda897b109fc50c20469783077419444870d478d0977568296fdae34943a02493d9cd3d9734f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f7ef16d6852af4dba442f37a9bd090c8ceecb3906a15c71686b10b65c6aef47f275c5ded9d3183b8943cb090b10f3069c678b8e572324038c12cf386ea09905e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3421c6ccccc5396a7b76f8f5b4c1014b957c8a2ed239096636e63f81871fdc190511427266a33844b92dbb79d8f99ad033c0645d2e6b4f4a59c7f31595d3220"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:55.696986) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:55.697344) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1473677868.181410-1371485991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004399 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 05:40:55.781623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:55.781958) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 05:40:55.782318) ========= ========= Total master_lag: 0.23 =========