builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1471602735.04 results: success (0) buildid: 20160819004015 builduid: 71500e51c7be4040aa3163130cd4ed8c revision: 0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.045068) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.045441) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.046001) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.074435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.074699) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 03:32:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-08-19 03:32:15 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.324213 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.412999) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.413302) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017959 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.462288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:15.462623) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0 --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 0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0 --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=9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 03:32:15,609 truncating revision to first 12 chars 2016-08-19 03:32:15,609 Setting DEBUG logging. 2016-08-19 03:32:15,609 attempt 1/10 2016-08-19 03:32:15,609 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0b2e1f4c1af3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 03:32:15,914 unpacking tar archive at: mozilla-aurora-0b2e1f4c1af3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661451 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:16.137467) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:16.137775) ========= script_repo_revision: 0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:16.138162) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:16.138455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 03:32:16.148311) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-19 03:32:16.148570) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:32:16 INFO - MultiFileLogger online at 20160819 03:32:16 in /builds/slave/test 03:32:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:32:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:32:16 INFO - {'append_to_log': False, 03:32:16 INFO - 'base_work_dir': '/builds/slave/test', 03:32:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:32:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:32:16 INFO - 'branch': 'Mozilla-Aurora', 03:32:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:32:16 INFO - 'config_files': ('talos/linux_config.py',), 03:32:16 INFO - 'default_actions': ('clobber', 03:32:16 INFO - 'read-buildbot-config', 03:32:16 INFO - 'download-and-extract', 03:32:16 INFO - 'populate-webroot', 03:32:16 INFO - 'create-virtualenv', 03:32:16 INFO - 'install', 03:32:16 INFO - 'run-tests'), 03:32:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:32:16 INFO - 'download_minidump_stackwalk': True, 03:32:16 INFO - 'download_symbols': 'ondemand', 03:32:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:32:16 INFO - 'tooltool.py': '/tools/tooltool.py', 03:32:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:32:16 INFO - '/tools/misc-python/virtualenv.py')}, 03:32:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:32:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:32:16 INFO - 'installer_path': 'installer.exe', 03:32:16 INFO - 'log_level': 'info', 03:32:16 INFO - 'log_name': 'talos', 03:32:16 INFO - 'log_to_console': True, 03:32:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:32:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:32:16 INFO - 'opt_config_files': (), 03:32:16 INFO - 'pip_index': False, 03:32:16 INFO - 'sps_profile': False, 03:32:16 INFO - 'sps_profile_interval': 0, 03:32:16 INFO - 'suite': 'dromaeojs', 03:32:16 INFO - 'system_bits': '32', 03:32:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:32:16 INFO - 'title': 'talos-linux64-ix-022', 03:32:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:32:16 INFO - 'use_talos_json': True, 03:32:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:32:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:32:16 INFO - 'work_dir': 'build'} 03:32:16 INFO - [mozharness: 2016-08-19 10:32:16.382419Z] Running clobber step. 03:32:16 INFO - Running pre-action listener: _resource_record_pre_action 03:32:16 INFO - Running main action method: clobber 03:32:16 INFO - rmtree: /builds/slave/test/build 03:32:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:32:17 INFO - Running post-action listener: _resource_record_post_action 03:32:17 INFO - [mozharness: 2016-08-19 10:32:17.343642Z] Finished clobber step (success) 03:32:17 INFO - [mozharness: 2016-08-19 10:32:17.343741Z] Running read-buildbot-config step. 03:32:17 INFO - Running pre-action listener: _resource_record_pre_action 03:32:17 INFO - Running main action method: read_buildbot_config 03:32:17 INFO - Using buildbot properties: 03:32:17 INFO - { 03:32:17 INFO - "project": "", 03:32:17 INFO - "product": "firefox", 03:32:17 INFO - "script_repo_revision": "production", 03:32:17 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:32:17 INFO - "repository": "", 03:32:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:32:17 INFO - "buildid": "20160819004015", 03:32:17 INFO - "stage_platform": "linux64-pgo", 03:32:17 INFO - "basedir": "/builds/slave/test", 03:32:17 INFO - "nightly_build": "True", 03:32:17 INFO - "buildnumber": 695, 03:32:17 INFO - "slavename": "talos-linux64-ix-022", 03:32:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:32:17 INFO - "platform": "ubuntu64_hw", 03:32:17 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:32:17 INFO - "branch": "mozilla-aurora", 03:32:17 INFO - "repo_path": "releases/mozilla-aurora", 03:32:17 INFO - "revision": "0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0", 03:32:17 INFO - "pgo_build": "True", 03:32:17 INFO - "builduid": "71500e51c7be4040aa3163130cd4ed8c", 03:32:17 INFO - "slavebuilddir": "test" 03:32:17 INFO - } 03:32:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:32:17 INFO - Running post-action listener: _resource_record_post_action 03:32:17 INFO - [mozharness: 2016-08-19 10:32:17.346219Z] Finished read-buildbot-config step (success) 03:32:17 INFO - [mozharness: 2016-08-19 10:32:17.346308Z] Running download-and-extract step. 03:32:17 INFO - Running pre-action listener: _resource_record_pre_action 03:32:17 INFO - Running main action method: download_and_extract 03:32:17 INFO - mkdir: /builds/slave/test/build/tests 03:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:32:17 INFO - https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:32:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:32:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:32:17 INFO - retry: Failed, sleeping 30 seconds before retrying 03:32:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:32:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:32:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:32:47 INFO - retry: Failed, sleeping 60 seconds before retrying 03:33:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:33:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:33:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:33:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:33:47 INFO - Caught exception: HTTP Error 404: Not Found 03:33:47 INFO - Caught exception: HTTP Error 404: Not Found 03:33:47 INFO - Caught exception: HTTP Error 404: Not Found 03:33:47 INFO - trying https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:33:47 INFO - Downloading https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:33:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:33:48 INFO - Downloaded 1361 bytes. 03:33:48 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:33:48 INFO - Using the following test package requirements: 03:33:48 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:33:48 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:33:48 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:33:48 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'jsshell-linux-x86_64.zip'], 03:33:48 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:33:48 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:33:48 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:33:48 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:33:48 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:33:48 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:33:48 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:33:48 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:33: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')]} 03:33:48 INFO - https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:33:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:33:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:33:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:33:48 INFO - retry: Failed, sleeping 30 seconds before retrying 03:34:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:34:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:34:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:34:18 INFO - retry: Failed, sleeping 60 seconds before retrying 03:35:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:35:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:35:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:35:18 INFO - Caught exception: HTTP Error 404: Not Found 03:35:18 INFO - Caught exception: HTTP Error 404: Not Found 03:35:18 INFO - Caught exception: HTTP Error 404: Not Found 03:35:18 INFO - trying https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:18 INFO - Downloading https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:35:18 INFO - Downloaded 21766596 bytes. 03:35:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:35:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:35:20 INFO - Return code: 0 03:35:20 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:35:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:35:20 INFO - https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:35:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:35:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:35:20 INFO - retry: Failed, sleeping 30 seconds before retrying 03:35:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:35:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:35:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:35:50 INFO - retry: Failed, sleeping 60 seconds before retrying 03:36:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:36:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:36:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:36:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:36:50 INFO - Caught exception: HTTP Error 404: Not Found 03:36:50 INFO - Caught exception: HTTP Error 404: Not Found 03:36:50 INFO - Caught exception: HTTP Error 404: Not Found 03:36:50 INFO - trying https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:36:50 INFO - Downloading https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:36:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:36:52 INFO - Downloaded 21766596 bytes. 03:36:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:36:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:36:53 INFO - Return code: 0 03:36:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:36:53 INFO - https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:36:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:36:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:36:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:36:53 INFO - retry: Failed, sleeping 30 seconds before retrying 03:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:37:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:37:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:37:23 INFO - retry: Failed, sleeping 60 seconds before retrying 03:38:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:38:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:38:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:38:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:38:23 INFO - Caught exception: HTTP Error 404: Not Found 03:38:23 INFO - Caught exception: HTTP Error 404: Not Found 03:38:23 INFO - Caught exception: HTTP Error 404: Not Found 03:38:23 INFO - trying https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:38:23 INFO - Downloading https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:38:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:38:24 INFO - Downloaded 14413723 bytes. 03:38:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:38:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:38:24 INFO - Return code: 0 03:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:38:24 INFO - https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:38:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:38:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:38:24 INFO - retry: Failed, sleeping 30 seconds before retrying 03:38:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:38:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:38:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:38:54 INFO - retry: Failed, sleeping 60 seconds before retrying 03:39:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:39:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:39:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:39:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:39:54 INFO - Caught exception: HTTP Error 404: Not Found 03:39:54 INFO - Caught exception: HTTP Error 404: Not Found 03:39:54 INFO - Caught exception: HTTP Error 404: Not Found 03:39:54 INFO - trying https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:39:54 INFO - Downloading https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:39:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:39:56 INFO - Downloaded 63745839 bytes. 03:39:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:39:56 INFO - mkdir: /builds/slave/test/properties 03:39:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:39:56 INFO - Writing to file /builds/slave/test/properties/build_url 03:39:56 INFO - Contents: 03:39:56 INFO - build_url:https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:39:56 INFO - Running post-action listener: _resource_record_post_action 03:39:56 INFO - Running post-action listener: set_extra_try_arguments 03:39:56 INFO - [mozharness: 2016-08-19 10:39:56.716528Z] Finished download-and-extract step (success) 03:39:56 INFO - [mozharness: 2016-08-19 10:39:56.716760Z] Running populate-webroot step. 03:39:56 INFO - Running pre-action listener: _resource_record_pre_action 03:39:56 INFO - Running main action method: populate_webroot 03:39:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:39:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:39:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:39:56 INFO - '--tppagecycles', 03:39:56 INFO - '7'], 03:39:56 INFO - 'tests': ['tsvgm']}}, 03:39:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:39:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:39:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:39:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:39:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:39:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:39:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['damp', 'tps']}, 03:39:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'tests': ['damp', 'tps']}, 03:39:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['dromaeo_dom']}, 03:39:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:39:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['basic_compositor_video']}, 03:39:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:39:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['a11yr', 03:39:56 INFO - 'ts_paint', 03:39:56 INFO - 'tpaint', 03:39:56 INFO - 'sessionrestore', 03:39:56 INFO - 'sessionrestore_no_auto_restore', 03:39:56 INFO - 'tabpaint']}, 03:39:56 INFO - 'other-e10s': {'tests': ['a11yr', 03:39:56 INFO - 'ts_paint', 03:39:56 INFO - 'tpaint', 03:39:56 INFO - 'sessionrestore', 03:39:56 INFO - 'sessionrestore_no_auto_restore', 03:39:56 INFO - 'tabpaint']}, 03:39:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:39:56 INFO - 'ts_paint', 03:39:56 INFO - 'tpaint', 03:39:56 INFO - 'sessionrestore', 03:39:56 INFO - 'sessionrestore_no_auto_restore', 03:39:56 INFO - 'tabpaint']}, 03:39:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:39:56 INFO - 'ts_paint', 03:39:56 INFO - 'tpaint', 03:39:56 INFO - 'sessionrestore', 03:39:56 INFO - 'sessionrestore_no_auto_restore', 03:39:56 INFO - 'tabpaint']}, 03:39:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['a11yr', 03:39:56 INFO - 'ts_paint', 03:39:56 INFO - 'tpaint', 03:39:56 INFO - 'sessionrestore', 03:39:56 INFO - 'sessionrestore_no_auto_restore', 03:39:56 INFO - 'tabpaint']}, 03:39:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['a11yr', 03:39:56 INFO - 'ts_paint', 03:39:56 INFO - 'tpaint', 03:39:56 INFO - 'sessionrestore', 03:39:56 INFO - 'sessionrestore_no_auto_restore', 03:39:56 INFO - 'tabpaint']}, 03:39:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['tsvgx', 03:39:56 INFO - 'tsvgr_opacity', 03:39:56 INFO - 'tart', 03:39:56 INFO - 'tscrollx', 03:39:56 INFO - 'cart']}, 03:39:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:39:56 INFO - 'tsvgr_opacity', 03:39:56 INFO - 'tart', 03:39:56 INFO - 'tscrollx', 03:39:56 INFO - 'cart']}, 03:39:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'talos_options': ['--disable-e10s'], 03:39:56 INFO - 'tests': ['tp5o']}, 03:39:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'tests': ['tp5o']}, 03:39:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'talos_options': ['--disable-e10s', 03:39:56 INFO - '--xperf_path', 03:39:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:39:56 INFO - 'tests': ['tp5n']}, 03:39:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:39:56 INFO - 'talos_options': ['--xperf_path', 03:39:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:39:56 INFO - 'tests': ['tp5n']}}, 03:39:56 INFO - 'talos.zip': {'path': '', 03:39:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:39:56 INFO - Running post-action listener: _resource_record_post_action 03:39:56 INFO - [mozharness: 2016-08-19 10:39:56.735411Z] Finished populate-webroot step (success) 03:39:56 INFO - [mozharness: 2016-08-19 10:39:56.735498Z] Running create-virtualenv step. 03:39:56 INFO - Running pre-action listener: _resource_record_pre_action 03:39:56 INFO - Running main action method: create_virtualenv 03:39:56 INFO - Creating virtualenv /builds/slave/test/build/venv 03:39:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:39:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:39:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:39:56 INFO - Using real prefix '/usr' 03:39:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:39:57 INFO - Installing distribute.............................................................................................................................................................................................done. 03:39:59 INFO - Installing pip.................done. 03:39:59 INFO - Return code: 0 03:39:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:39: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:39: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', 'pip>=1.5'] in /builds/slave/test/build 03:39: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 pip>=1.5 03:39:59 INFO - Using env: {'DISPLAY': ':0', 03:39:59 INFO - 'HOME': '/home/cltbld', 03:39:59 INFO - 'LANG': 'en_US.UTF-8', 03:39:59 INFO - 'LANGUAGE': 'en_US:en', 03:39:59 INFO - 'LOGNAME': 'cltbld', 03:39:59 INFO - 'MAIL': '/var/mail/cltbld', 03:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:59 INFO - 'MOZ_NO_REMOTE': '1', 03:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:59 INFO - 'NO_EM_RESTART': '1', 03:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:59 INFO - 'PWD': '/builds/slave/test', 03:39:59 INFO - 'SHELL': '/bin/bash', 03:39:59 INFO - 'SHLVL': '1', 03:39:59 INFO - 'TERM': 'linux', 03:39:59 INFO - 'TMOUT': '86400', 03:39:59 INFO - 'USER': 'cltbld', 03:39:59 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:59 INFO - '_': '/tools/buildbot/bin/python'} 03:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:39:59 INFO - Cleaning up... 03:39:59 INFO - Return code: 0 03:39:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:39:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:39: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', '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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:39: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:39: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 psutil>=3.1.1 03:39:59 INFO - Using env: {'DISPLAY': ':0', 03:39:59 INFO - 'HOME': '/home/cltbld', 03:39:59 INFO - 'LANG': 'en_US.UTF-8', 03:39:59 INFO - 'LANGUAGE': 'en_US:en', 03:39:59 INFO - 'LOGNAME': 'cltbld', 03:39:59 INFO - 'MAIL': '/var/mail/cltbld', 03:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:59 INFO - 'MOZ_NO_REMOTE': '1', 03:39:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:59 INFO - 'NO_EM_RESTART': '1', 03:39:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:59 INFO - 'PWD': '/builds/slave/test', 03:39:59 INFO - 'SHELL': '/bin/bash', 03:39:59 INFO - 'SHLVL': '1', 03:39:59 INFO - 'TERM': 'linux', 03:39:59 INFO - 'TMOUT': '86400', 03:39:59 INFO - 'USER': 'cltbld', 03:39:59 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:59 INFO - '_': '/tools/buildbot/bin/python'} 03:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:39:59 INFO - Downloading/unpacking psutil>=3.1.1 03:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:39:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:39:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:40:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:01 INFO - Installing collected packages: psutil 03:40:01 INFO - Running setup.py install for psutil 03:40:01 INFO - building 'psutil._psutil_linux' extension 03:40:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:40:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:40:01 INFO - building 'psutil._psutil_posix' extension 03:40:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:40:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:40:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:02 INFO - Successfully installed psutil 03:40:02 INFO - Cleaning up... 03:40:02 INFO - Return code: 0 03:40:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:40:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:40:02 INFO - Using env: {'DISPLAY': ':0', 03:40:02 INFO - 'HOME': '/home/cltbld', 03:40:02 INFO - 'LANG': 'en_US.UTF-8', 03:40:02 INFO - 'LANGUAGE': 'en_US:en', 03:40:02 INFO - 'LOGNAME': 'cltbld', 03:40:02 INFO - 'MAIL': '/var/mail/cltbld', 03:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:02 INFO - 'MOZ_NO_REMOTE': '1', 03:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:02 INFO - 'NO_EM_RESTART': '1', 03:40:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:02 INFO - 'PWD': '/builds/slave/test', 03:40:02 INFO - 'SHELL': '/bin/bash', 03:40:02 INFO - 'SHLVL': '1', 03:40:02 INFO - 'TERM': 'linux', 03:40:02 INFO - 'TMOUT': '86400', 03:40:02 INFO - 'USER': 'cltbld', 03:40:02 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:02 INFO - '_': '/tools/buildbot/bin/python'} 03:40:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:40:05 INFO - Installing collected packages: mozsystemmonitor 03:40:05 INFO - Running setup.py install for mozsystemmonitor 03:40:05 INFO - Successfully installed mozsystemmonitor 03:40:05 INFO - Cleaning up... 03:40:05 INFO - Return code: 0 03:40:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:05 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:40:05 INFO - Using env: {'DISPLAY': ':0', 03:40:05 INFO - 'HOME': '/home/cltbld', 03:40:05 INFO - 'LANG': 'en_US.UTF-8', 03:40:05 INFO - 'LANGUAGE': 'en_US:en', 03:40:05 INFO - 'LOGNAME': 'cltbld', 03:40:05 INFO - 'MAIL': '/var/mail/cltbld', 03:40:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:05 INFO - 'MOZ_NO_REMOTE': '1', 03:40:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:05 INFO - 'NO_EM_RESTART': '1', 03:40:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:05 INFO - 'PWD': '/builds/slave/test', 03:40:05 INFO - 'SHELL': '/bin/bash', 03:40:05 INFO - 'SHLVL': '1', 03:40:05 INFO - 'TERM': 'linux', 03:40:05 INFO - 'TMOUT': '86400', 03:40:05 INFO - 'USER': 'cltbld', 03:40:05 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:05 INFO - '_': '/tools/buildbot/bin/python'} 03:40:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:05 INFO - Downloading/unpacking jsonschema==2.5.1 03:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:40:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:08 INFO - Downloading functools32-3.2.3-2.tar.gz 03:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:40:08 INFO - warning: no files found matching '*.txt' 03:40:08 INFO - no previously-included directories found matching 'build' 03:40:08 INFO - no previously-included directories found matching 'dist' 03:40:08 INFO - no previously-included directories found matching '.git*' 03:40:08 INFO - Installing collected packages: jsonschema, functools32 03:40:08 INFO - Running setup.py install for functools32 03:40:08 INFO - warning: no files found matching '*.txt' 03:40:08 INFO - no previously-included directories found matching 'build' 03:40:08 INFO - no previously-included directories found matching 'dist' 03:40:08 INFO - no previously-included directories found matching '.git*' 03:40:08 INFO - Successfully installed jsonschema functools32 03:40:08 INFO - Cleaning up... 03:40:08 INFO - Return code: 0 03:40:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:08 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:40:08 INFO - Using env: {'DISPLAY': ':0', 03:40:08 INFO - 'HOME': '/home/cltbld', 03:40:08 INFO - 'LANG': 'en_US.UTF-8', 03:40:08 INFO - 'LANGUAGE': 'en_US:en', 03:40:08 INFO - 'LOGNAME': 'cltbld', 03:40:08 INFO - 'MAIL': '/var/mail/cltbld', 03:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:08 INFO - 'MOZ_NO_REMOTE': '1', 03:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:08 INFO - 'NO_EM_RESTART': '1', 03:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:08 INFO - 'PWD': '/builds/slave/test', 03:40:08 INFO - 'SHELL': '/bin/bash', 03:40:08 INFO - 'SHLVL': '1', 03:40:08 INFO - 'TERM': 'linux', 03:40:08 INFO - 'TMOUT': '86400', 03:40:08 INFO - 'USER': 'cltbld', 03:40:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:08 INFO - '_': '/tools/buildbot/bin/python'} 03:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:40:08 INFO - Cleaning up... 03:40:08 INFO - Return code: 0 03:40:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:08 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:40:08 INFO - Using env: {'DISPLAY': ':0', 03:40:08 INFO - 'HOME': '/home/cltbld', 03:40:08 INFO - 'LANG': 'en_US.UTF-8', 03:40:08 INFO - 'LANGUAGE': 'en_US:en', 03:40:08 INFO - 'LOGNAME': 'cltbld', 03:40:08 INFO - 'MAIL': '/var/mail/cltbld', 03:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:08 INFO - 'MOZ_NO_REMOTE': '1', 03:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:08 INFO - 'NO_EM_RESTART': '1', 03:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:08 INFO - 'PWD': '/builds/slave/test', 03:40:08 INFO - 'SHELL': '/bin/bash', 03:40:08 INFO - 'SHLVL': '1', 03:40:08 INFO - 'TERM': 'linux', 03:40:08 INFO - 'TMOUT': '86400', 03:40:08 INFO - 'USER': 'cltbld', 03:40:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:08 INFO - '_': '/tools/buildbot/bin/python'} 03:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:08 INFO - Downloading/unpacking blobuploader==1.2.4 03:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:10 INFO - Downloading blobuploader-1.2.4.tar.gz 03:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:40:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:40:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:11 INFO - Downloading docopt-0.6.1.tar.gz 03:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:40:11 INFO - Installing collected packages: blobuploader, requests, docopt 03:40:11 INFO - Running setup.py install for blobuploader 03:40:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:40:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:40:11 INFO - Running setup.py install for requests 03:40:11 INFO - Running setup.py install for docopt 03:40:12 INFO - Successfully installed blobuploader requests docopt 03:40:12 INFO - Cleaning up... 03:40:12 INFO - Return code: 0 03:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:12 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:40:12 INFO - Using env: {'DISPLAY': ':0', 03:40:12 INFO - 'HOME': '/home/cltbld', 03:40:12 INFO - 'LANG': 'en_US.UTF-8', 03:40:12 INFO - 'LANGUAGE': 'en_US:en', 03:40:12 INFO - 'LOGNAME': 'cltbld', 03:40:12 INFO - 'MAIL': '/var/mail/cltbld', 03:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:12 INFO - 'MOZ_NO_REMOTE': '1', 03:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:12 INFO - 'NO_EM_RESTART': '1', 03:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:12 INFO - 'PWD': '/builds/slave/test', 03:40:12 INFO - 'SHELL': '/bin/bash', 03:40:12 INFO - 'SHLVL': '1', 03:40:12 INFO - 'TERM': 'linux', 03:40:12 INFO - 'TMOUT': '86400', 03:40:12 INFO - 'USER': 'cltbld', 03:40:12 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:12 INFO - '_': '/tools/buildbot/bin/python'} 03:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:12 INFO - Running setup.py (path:/tmp/pip-T_AwWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:12 INFO - Running setup.py (path:/tmp/pip-jM7C21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:12 INFO - Running setup.py (path:/tmp/pip-rszFrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:12 INFO - Running setup.py (path:/tmp/pip-GIyXbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:12 INFO - Running setup.py (path:/tmp/pip-_qvrke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:12 INFO - Running setup.py (path:/tmp/pip-3NwTX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:12 INFO - Running setup.py (path:/tmp/pip-4PS61h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:12 INFO - Running setup.py (path:/tmp/pip-t4m9AM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:12 INFO - Running setup.py (path:/tmp/pip-9enqh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:12 INFO - Running setup.py (path:/tmp/pip-8UhAsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:13 INFO - Running setup.py (path:/tmp/pip-FMcPWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:13 INFO - Running setup.py (path:/tmp/pip-lR2S_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:13 INFO - Running setup.py (path:/tmp/pip-qYxtpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:13 INFO - Running setup.py (path:/tmp/pip-f583P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:13 INFO - Running setup.py (path:/tmp/pip-rcmBEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:13 INFO - Running setup.py (path:/tmp/pip-COOaZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:13 INFO - Running setup.py (path:/tmp/pip-lzrmp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:40:13 INFO - Running setup.py install for manifestparser 03:40:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:40:13 INFO - Running setup.py install for mozcrash 03:40:13 INFO - Running setup.py install for mozdebug 03:40:13 INFO - Running setup.py install for mozdevice 03:40:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:40:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:40:13 INFO - Running setup.py install for mozfile 03:40:14 INFO - Running setup.py install for mozhttpd 03:40:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for mozinfo 03:40:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for mozInstall 03:40:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for mozleak 03:40:14 INFO - Running setup.py install for mozlog 03:40:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for moznetwork 03:40:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for mozprocess 03:40:14 INFO - Running setup.py install for mozprofile 03:40:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for mozrunner 03:40:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:40:14 INFO - Running setup.py install for mozscreenshot 03:40:15 INFO - Running setup.py install for moztest 03:40:15 INFO - Running setup.py install for mozversion 03:40:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:40:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:40:15 INFO - Cleaning up... 03:40:15 INFO - Return code: 0 03:40:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:15 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:40:15 INFO - Using env: {'DISPLAY': ':0', 03:40:15 INFO - 'HOME': '/home/cltbld', 03:40:15 INFO - 'LANG': 'en_US.UTF-8', 03:40:15 INFO - 'LANGUAGE': 'en_US:en', 03:40:15 INFO - 'LOGNAME': 'cltbld', 03:40:15 INFO - 'MAIL': '/var/mail/cltbld', 03:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:15 INFO - 'MOZ_NO_REMOTE': '1', 03:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:15 INFO - 'NO_EM_RESTART': '1', 03:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:15 INFO - 'PWD': '/builds/slave/test', 03:40:15 INFO - 'SHELL': '/bin/bash', 03:40:15 INFO - 'SHLVL': '1', 03:40:15 INFO - 'TERM': 'linux', 03:40:15 INFO - 'TMOUT': '86400', 03:40:15 INFO - 'USER': 'cltbld', 03:40:15 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:15 INFO - '_': '/tools/buildbot/bin/python'} 03:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:40:15 INFO - Running setup.py (path:/tmp/pip-y0CFTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:40:15 INFO - Running setup.py (path:/tmp/pip-TxAp8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:40:15 INFO - Running setup.py (path:/tmp/pip-xI7okT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:40:15 INFO - Running setup.py (path:/tmp/pip-YXy_Fe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:40:15 INFO - Running setup.py (path:/tmp/pip-mPCRRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:40:15 INFO - Running setup.py (path:/tmp/pip-S1qRq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:40:15 INFO - Running setup.py (path:/tmp/pip-6atUSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:40:16 INFO - Running setup.py (path:/tmp/pip-unQP8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:40:16 INFO - Running setup.py (path:/tmp/pip-9rDYYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:40:16 INFO - Running setup.py (path:/tmp/pip-SqAEvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:40:16 INFO - Running setup.py (path:/tmp/pip-WT8Wpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:40:16 INFO - Running setup.py (path:/tmp/pip-TMH5ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:40:16 INFO - Running setup.py (path:/tmp/pip-vpEm44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:40:16 INFO - Running setup.py (path:/tmp/pip-LCqV0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:16 INFO - Running setup.py (path:/tmp/pip-kCxROL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:40:16 INFO - Running setup.py (path:/tmp/pip-9HQXtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:40:16 INFO - Running setup.py (path:/tmp/pip-_1by0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:40:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:40:18 INFO - Downloading blessings-1.6.tar.gz 03:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:40:18 INFO - Installing collected packages: blessings 03:40:18 INFO - Running setup.py install for blessings 03:40:18 INFO - Successfully installed blessings 03:40:18 INFO - Cleaning up... 03:40:18 INFO - Return code: 0 03:40:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:40:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:19 INFO - Reading from file tmpfile_stdout 03:40:19 INFO - Current package versions: 03:40:19 INFO - argparse == 1.2.1 03:40:19 INFO - blessings == 1.6 03:40:19 INFO - blobuploader == 1.2.4 03:40:19 INFO - docopt == 0.6.1 03:40:19 INFO - functools32 == 3.2.3-2 03:40:19 INFO - jsonschema == 2.5.1 03:40:19 INFO - manifestparser == 1.1 03:40:19 INFO - mozInstall == 1.12 03:40:19 INFO - mozcrash == 0.17 03:40:19 INFO - mozdebug == 0.1 03:40:19 INFO - mozdevice == 0.48 03:40:19 INFO - mozfile == 1.2 03:40:19 INFO - mozhttpd == 0.7 03:40:19 INFO - mozinfo == 0.9 03:40:19 INFO - mozleak == 0.1 03:40:19 INFO - mozlog == 3.3 03:40:19 INFO - moznetwork == 0.27 03:40:19 INFO - mozprocess == 0.23 03:40:19 INFO - mozprofile == 0.28 03:40:19 INFO - mozrunner == 6.12 03:40:19 INFO - mozscreenshot == 0.1 03:40:19 INFO - mozsystemmonitor == 0.3 03:40:19 INFO - moztest == 0.7 03:40:19 INFO - mozversion == 1.4 03:40:19 INFO - psutil == 3.1.1 03:40:19 INFO - requests == 1.2.3 03:40:19 INFO - wsgiref == 0.1.2 03:40:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:19 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:40:19 INFO - Using env: {'DISPLAY': ':0', 03:40:19 INFO - 'HOME': '/home/cltbld', 03:40:19 INFO - 'LANG': 'en_US.UTF-8', 03:40:19 INFO - 'LANGUAGE': 'en_US:en', 03:40:19 INFO - 'LOGNAME': 'cltbld', 03:40:19 INFO - 'MAIL': '/var/mail/cltbld', 03:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:19 INFO - 'MOZ_NO_REMOTE': '1', 03:40:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:19 INFO - 'NO_EM_RESTART': '1', 03:40:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:19 INFO - 'PWD': '/builds/slave/test', 03:40:19 INFO - 'SHELL': '/bin/bash', 03:40:19 INFO - 'SHLVL': '1', 03:40:19 INFO - 'TERM': 'linux', 03:40:19 INFO - 'TMOUT': '86400', 03:40:19 INFO - 'USER': 'cltbld', 03:40:19 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:19 INFO - '_': '/tools/buildbot/bin/python'} 03:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:40:19 INFO - Cleaning up... 03:40:19 INFO - Return code: 0 03:40:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:19 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 0x12e6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:40:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:40:19 INFO - Using env: {'DISPLAY': ':0', 03:40:19 INFO - 'HOME': '/home/cltbld', 03:40:19 INFO - 'LANG': 'en_US.UTF-8', 03:40:19 INFO - 'LANGUAGE': 'en_US:en', 03:40:19 INFO - 'LOGNAME': 'cltbld', 03:40:19 INFO - 'MAIL': '/var/mail/cltbld', 03:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:19 INFO - 'MOZ_NO_REMOTE': '1', 03:40:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:19 INFO - 'NO_EM_RESTART': '1', 03:40:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:19 INFO - 'PWD': '/builds/slave/test', 03:40:19 INFO - 'SHELL': '/bin/bash', 03:40:19 INFO - 'SHLVL': '1', 03:40:19 INFO - 'TERM': 'linux', 03:40:19 INFO - 'TMOUT': '86400', 03:40:19 INFO - 'USER': 'cltbld', 03:40:19 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:19 INFO - '_': '/tools/buildbot/bin/python'} 03:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:40:19 INFO - Cleaning up... 03:40:19 INFO - Return code: 0 03:40:19 INFO - Running post-action listener: _resource_record_post_action 03:40:19 INFO - Running post-action listener: _start_resource_monitoring 03:40:19 INFO - Starting resource monitoring. 03:40:19 INFO - [mozharness: 2016-08-19 10:40:19.576813Z] Finished create-virtualenv step (success) 03:40:19 INFO - [mozharness: 2016-08-19 10:40:19.577248Z] Running install step. 03:40:19 INFO - Running pre-action listener: _resource_record_pre_action 03:40:19 INFO - Running main action method: install 03:40:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:40:19 INFO - Reading from file tmpfile_stdout 03:40:19 INFO - Detecting whether we're running mozinstall >=1.0... 03:40:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:40:19 INFO - Reading from file tmpfile_stdout 03:40:19 INFO - Output received: 03:40:19 INFO - Usage: mozinstall [options] installer 03:40:19 INFO - Options: 03:40:19 INFO - -h, --help show this help message and exit 03:40:19 INFO - -d DEST, --destination=DEST 03:40:19 INFO - Directory to install application into. [default: 03:40:19 INFO - "/builds/slave/test"] 03:40:19 INFO - --app=APP Application being installed. [default: firefox] 03:40:19 INFO - mkdir: /builds/slave/test/build/application 03:40:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:40:36 INFO - Reading from file tmpfile_stdout 03:40:36 INFO - Output received: 03:40:36 INFO - /builds/slave/test/build/application/firefox/firefox 03:40:36 INFO - Running post-action listener: _resource_record_post_action 03:40:36 INFO - [mozharness: 2016-08-19 10:40:36.413549Z] Finished install step (success) 03:40:36 INFO - [mozharness: 2016-08-19 10:40:36.413826Z] Running run-tests step. 03:40:36 INFO - Running pre-action listener: _resource_record_pre_action 03:40:36 INFO - Running main action method: run_tests 03:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:40:36 INFO - Python 2.7.3 03:40:36 INFO - Return code: 0 03:40:36 INFO - grabbing minidump binary from tooltool 03:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:36 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 0x15a53b0>, '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 0x15a62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a6740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:40:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:40:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:40:36 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:40:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:40:36 INFO - Return code: 0 03:40:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:40:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:40:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:40:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:40:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:40:36 INFO - Using env: {'DISPLAY': ':0', 03:40:36 INFO - 'HOME': '/home/cltbld', 03:40:36 INFO - 'LANG': 'en_US.UTF-8', 03:40:36 INFO - 'LANGUAGE': 'en_US:en', 03:40:36 INFO - 'LOGNAME': 'cltbld', 03:40:36 INFO - 'MAIL': '/var/mail/cltbld', 03:40:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:40:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:36 INFO - 'MOZ_NO_REMOTE': '1', 03:40:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:40:36 INFO - 'NO_EM_RESTART': '1', 03:40:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:36 INFO - 'PWD': '/builds/slave/test', 03:40:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:40:36 INFO - 'SHELL': '/bin/bash', 03:40:36 INFO - 'SHLVL': '1', 03:40:36 INFO - 'TERM': 'linux', 03:40:36 INFO - 'TMOUT': '86400', 03:40:36 INFO - 'USER': 'cltbld', 03:40:36 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664', 03:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:36 INFO - '_': '/tools/buildbot/bin/python'} 03:40:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:40:36 INFO - mozversion application_buildid: 20160819004015 03:40:36 INFO - mozversion application_changeset: 0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0 03:40:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:40:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:40:36 INFO - mozversion application_name: Firefox 03:40:36 INFO - mozversion application_remotingname: firefox-dev 03:40:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:40:36 INFO - mozversion application_vendor: Mozilla 03:40:36 INFO - mozversion application_version: 50.0a2 03:40:36 INFO - mozversion platform_buildid: 20160819004015 03:40:36 INFO - mozversion platform_changeset: 0b2e1f4c1af39800b1e33d2f2e5e88f9aee5e8a0 03:40:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:40:36 INFO - mozversion platform_version: 50.0a2 03:40:36 INFO - using testdate: 1471603236 03:40:36 INFO - actual date: 1471603236 03:40:36 INFO - starting webserver on 'localhost:33882' 03:40:36 INFO - SUITE-START | Running 2 tests 03:40:36 INFO - TEST-START | dromaeo_css 03:40:36 INFO - operating with platform_type : linux_ 03:40:36 INFO - Initialising browser for dromaeo_css test... 03:40:36 INFO - TEST-INFO | started process 10798 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9cQcXQ/profile http://localhost:33882/getInfo.html) 03:40:40 INFO - PROCESS | 10798 | __metrics Screen width/height:1600/1200 03:40:40 INFO - PROCESS | 10798 | colorDepth:24 03:40:40 INFO - PROCESS | 10798 | Browser inner width/height: 1024/697 03:40:40 INFO - PROCESS | 10798 | __metrics 03:40:41 INFO - PROCESS | 10798 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:40:41 INFO - PROCESS | 10798 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:40:42 INFO - TEST-INFO | 10798: exit 0 03:40:42 INFO - Browser initialized. 03:40:42 INFO - Running cycle 1/1 for dromaeo_css test... 03:40:42 INFO - TEST-INFO | started process 10886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9cQcXQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:54 INFO - PROCESS | 10886 | RSS: Main: 145240064 03:40:54 INFO - PROCESS | 10886 | 03:41:45 INFO - PROCESS | 10886 | 1471603305192 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:43:54 INFO - PROCESS | 10886 | Cycle 1(1): loaded http://localhost:33882/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33882/tests/dromaeo/cssquery-ext.html) 03:43:55 INFO - PROCESS | 10886 | RSS: Main: 291495936 03:43:55 INFO - PROCESS | 10886 | 03:46:58 INFO - PROCESS | 10886 | Cycle 1(1): loaded http://localhost:33882/tests/dromaeo/cssquery-ext.html (next: http://localhost:33882/tests/dromaeo/cssquery-jquery.html) 03:46:58 INFO - PROCESS | 10886 | RSS: Main: 242294784 03:46:58 INFO - PROCESS | 10886 | 03:50:00 INFO - PROCESS | 10886 | Cycle 1(1): loaded http://localhost:33882/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33882/tests/dromaeo/cssquery-mootools.html) 03:50:01 INFO - PROCESS | 10886 | RSS: Main: 177487872 03:50:01 INFO - PROCESS | 10886 | 03:53:03 INFO - PROCESS | 10886 | Cycle 1(1): loaded http://localhost:33882/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33882/tests/dromaeo/cssquery-prototype.html) 03:53:03 INFO - PROCESS | 10886 | RSS: Main: 201232384 03:53:03 INFO - PROCESS | 10886 | 03:56:06 INFO - PROCESS | 10886 | Cycle 1(1): loaded http://localhost:33882/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33882/tests/dromaeo/cssquery-yui.html) 03:56:06 INFO - PROCESS | 10886 | RSS: Main: 178704384 03:56:06 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | Cycle 1(1): loaded http://localhost:33882/tests/dromaeo/cssquery-yui.html (next: http://localhost:33882/tests/dromaeo/cssquery-dojo.html) 03:59:09 INFO - PROCESS | 10886 | RSS: Main: 259842048 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | __start_tp_report 03:59:09 INFO - PROCESS | 10886 | _x_x_mozilla_page_load 03:59:09 INFO - PROCESS | 10886 | _x_x_mozilla_page_load_details 03:59:09 INFO - PROCESS | 10886 | |i|pagename|runs| 03:59:09 INFO - PROCESS | 10886 | |0;dojo.html;3239.36832317698;3250.9837450812743;3258.7555933305002;3263.298390845968;3295.769296149269;5520.06158952978;5595.916061259074;5599.608027438076;5621.550275977917;5624.606277560572;5668.461496157869;5729.05470597352;5731.426857314266;5740.741666625012;5773.162891380758;5027.4469808321055;5111.309973153614;5165.63840531163;5199.220116982464;5252.842414727565;5187.221916712486;5229.86925326868;5233.607479439038;5234.162533994561;5234.7644356004075;5107.565856902149;5141.76862041209;5152.072626927152;5161.148410512261;5222.164453687411;5236.4763523647525;5262.763175657066;5264.6051546134195;5275.024120537693;5302.946970530284;5038.5465308122075;5062.746862656889;5113.130767769489;5135.101357262502;5162.612804039674;5587.3015873015875;5596.916046259309;5597.608167428281;5631.831045068655;5637.379888212294;6105.908411373788;6112.174856394375;6117.449429551361;6118.329570431041;6120.632762034292;5896.911546326808;5979.4618484336615;6014.789482368096;6064.454199122056;6139.2325959255095;5710.257666503369;5723.027085395493;5734.856628584319;5735.426457354273;5746.42535746422;6209.410106039919;6232.594881332699;6232.844178895473;6237.438630523275;6244.438000579969;5830.737616807254;5921.348651648315;5924.7926322578505;5933.643981361132;6046.425589568984;6075.119107729354;6085.753803596121;6121.571489995657;6123.08153776937;6143.600665956698;5991.221141251696;6032.758689652364;6042.758289668364;6056.878862422727;6063.9090413643835;5694;5956.195913551702;6009.1587177795145;6017.338092809788;6050.152978583002;4617.0996655651825;4644.233701439225;4646.233371493733;4652.1626107300335;4678.883027924261;4114.136031433432;4125.23683118618;4131.132462182975;4159.355299928516;4160.417541544186;5612.831615051555;5741.483266506035;5761.971190144106;5776.249087618667;5787.363390027093;6109.726306153001;6110.820830534146;6116.42707509198;6119.228375855631;6120.721420533169;5629.549636029108;5810.680412577264;5818.185454036438;5825.271841019872;5842.444967728059;5150.639455238173;5191.506806853343;5209.661372010807;5258.474152584788;5264.6051546134195;6270.780522681775;6301.77943771975;6312.873742525124;6317.810465686037;6317.842053948688;6014.158017877413;6051.062085376773;6068.302145253352;6070.392960704071;6111.083337499233;6120.571559990758;6132.969335153296;6135.416406698588;6137.62214577108;6165.3526379731275;5887.970560147171;5902.970485147547;5903.7048147593305;5904.055351143968;5945.10823376497;5999.310079340931;6005.039193728982;6010.879782404415;6019.277686677627;6023.849403764941;5800.9419905800405;5826.854328641818;5873.295204575478;5951.256092988377;5952.434518720715;5923.229980102731;5923.496502797138;5930.11048342752;5936.614120082181;5942.078977758454;5704.3154821421695;5740.454656807597;5753.597248192586;5827.155062515996;5852.707364631837;5915.349311575637;5920.200772895604;5948.791792287249;5964.493018093511;5972.402759723993;5764.913526297025;5767.509761670137;5772.884542309214;5778.04662230744;5781.132830075354 03:59:09 INFO - PROCESS | 10886 | |1;ext.html;606.0817860940675;647.9254885688146;668.7458765669045;673.7170388436856;679.0459404536628;34903.82548087259;45282;47161.764191179;47182.52817471829;47272;48178;48750;48772.26841597379;48852.2672159917;48877.26684099733;9233.76915577111;9366.672166474174;9370.250379969586;9374.109459601326;9376.296777741696;8474.533900635462;10916.727081822939;11190;11200.831987520194;11572.537098516092;11240.887591124067;11414.771704565903;11416.54333826646;11420.657380278564;11421.371824549646;11172;11185.384803835786;11195.664130076111;11198.160137989684;11222.494987725571;11094.223404361697;11097.3896435696;11166.330020198815;11181.552737890475;11186.384748838811;45924;46063.309050364274;46165;46185.53814461846;46235;3768.7361884668085;3869.7097717671004;4181.665466762652;4183.832646694129;4567.611753000999;34633.422498374595;35135.29729405398;35181.12047198815;35424.29151416957;35701.46447803285;4527.139843429771;4532.9773351133035;4552.8861778455475;4580.358749775034;4597.310403439477;360194;360751;361091;362451;362952.9999999974;65308.673456632416;65411;65703.67148164229;65756.67121664456;65808;39251.60748392536;40379.59620403817;40651.390229146586;42473.36289955591;42533.78733106315;17190.74213886793;17284.91357543204;17313;17338.13309334528;17339.913300433418;11669.941650291694;12928.095033347763;12936.935315323362;12988.960883129326;12998.285094319903;20564.897175514325;21043.579128417347;21166.259180928595;21231.256906008217;21237.787622123582;7702.268284512962;7860.135385107634;7909.604519773989;7927.265093542718;7974.202579742096;1004.3973615830444;1064.3986147826454;1152.792497350485;1173.167051393503;1228.4778968938344;34605.65394346075;35007;35072.64927350694;35089.298214035574;35347;1195.3067221011966;1202.2606097250127;1215.6778453709774;1244.1042449436572;1260.7226410189744;17312.653746924992;17372.826271737373;17531.386401475887;17879.837810542274;18060.27758889656;640213;645294;646107;646674;647182;64843.35156648468;65348;65350.67324663441;65416.018759718645;65586;1350.5340657473157;1392.463901397969;1454.1057249791513;1483.621676472514;1489.0470099136562;31666.366672665958;36345;36385.27229455449;36694.44958325681;36727.449088263165;54377.18434223569;55510;55510.72244638751;55535.44464555464;55561.444385557246;410071;412016;414197;414428;415031;27826;28259.434811303247;29228.853855730584;31584.36831263316;33346.666533334355;52788.20817687814;53105.4689453116;53306;53763.462365375846;54319;44546.55453445424;45401;45846.31230531611;46016.59585502547;46592.301115482616;44813.551864482244;46356;46765.76617116893;48809.5119048805;49143.262851057974;44621;45489;45609.54390456053;45860.770696146305;45990.540094598626;39163;40710.59289407068;40831;40908.795456022526;41919.580804191566;30153.246168845955;30474.390512189188;30717.692823071484;30740.538891916192;30985.845070774503 03:59:09 INFO - PROCESS | 10886 | |2;jquery.html;393.6830851164811;393.7971944448608;395.9208158368326;402.46271227910745;423.758457679123;14105.017367250734;20255.291064812736;20291.08690108945;20307.796922030775;20696.896515517437;11855.940720296408;12330.383480825945;12340.13619046667;12354.702756210603;12386.876131238685;4040.5353384360883;5312.256284120216;5476.835694929158;5486.31421072366;5781.53747700183;6019.72911218994;6042.335343112276;6113.816585502442;6118.908216376758;6124.234470691164;5852.882942341151;5857.238558987347;5888.057910734284;5900.1739756434135;6102.755889764404;5819.408676209301;5937.317208521011;5945.85135371617;5953.940460595382;5969.552283578727;5741.081426971707;5850.6782126983;5852.473277405012;5853.239078919713;5855.151003104568;13654.795178072336;13742.450301987908;19833.80166198334;23332.416689582897;23509.177178798833;2357.65813956977;2635.4729054189047;2642.8150029497942;2693.824901381404;2765.5160346939206;10222.846657300146;10462.843057354146;10619;10677.78644427107;10752.569897204103;1923.1730355946938;1964.7642282925997;2043.1418804102314;2086.5826834633135;2136.2523116908956;82689.58655206746;85301;86360.56819715924;87571;89063.55468222682;11439.313641181556;11800.52797888092;11873.287602743863;11897;11972.820407693891;16696.165191740365;16697.415590454275;16714.83285167157;16719.331226750794;16731.247093880804;8414.894704310736;8556.443831150955;8680;8686.652533898574;8742.956285218661;8267.710630127916;8291.917080829235;8301.8339633207;8450.915490845136;8492.447990880573;4601.631869450436;5027.396712394536;5054.292399064134;5117.923231151536;5119.795208191631;1961.3625571689258;2056.568120694641;2093.9267125650836;2096.716943212677;2119.724435823333;961.3174646001418;1021.39737554843;1025.6153942271649;1069.3690722473777;1086.8971272732654;19326.806731932782;20991.580168396853;20998.115348660547;21114.78885211159;21310;1019.5157300282307;1048.533402635835;1078.8813230544633;1084.9945750271197;1141.1612464838338;10863.782724345469;11661.591844285409;11712.238704484182;11797.52809887595;11827.645170644895;574240;576347;581932;584084;597093;91881;94196.52901735448;95006.5249673761;96857;97907;993.801239752038;1008.0725732326277;1021.8467229915572;1033.4419413516766;1058.7194393485731;17829.286828527234;18291.359802406852;18424.078796059875;18478.6300877175;18725.531861703566;41575.58424415717;43846.34230486609;44339;44435.33346999862;44609.330860036476;53986;63175.368246316946;64076;65347.34652653413;65446.672766635864;8296.294814940798;8421;8475.788105297417;8674.739757807276;8740.21338079576;10212.314968030489;12756.61730148097;13760.17438953666;13951.511697090958;14105.6473588157;11369.4883730234;11962.820557691815;12240.816387754368;12278.07914406441;12297.815532767194;11864.76270474603;11972.820407694066;12244.938775306422;12255.938720306698;12409.689757756129;11950.581729639422;12178.939105304416;12411.131220814455;12427.130100892851;12545.87454125447;8247.917520824954;8413.495190288602;8440.535770532433;8569;8906.287497000354;10602.681919542427;12175;12280.81578776337;12337.50649973991;12583.119181657195 03:59:09 INFO - PROCESS | 10886 | |3;mootools.html;1426.5007247463395;1495.9027663201894;1581.4385893007982;1731.2469075951963;1898.2312163573758;8455.32357411407;9263;9534.570944307505;9541.809163816719;9785.21718262539;9486.146246837783;9546.188573971222;9548.618055277799;9559.904400955988;9563.091506306908;2478.9380265493373;2505.5991041433376;2506.9373265668323;2507.4734305795714;2507.912223072193;2288.8855557222155;2504.561701702204;2506.736792636774;2506.786923111538;2507.674002379688;2443.1204766284213;2459.8155138364605;2511;2512.208654273907;2514.6479492871013;2399.2442380650127;2424.903003879843;2439.8048156147465;2441.121196369306;2448.3879030242438;2397.832151749378;2425.866577338246;2426.3691440225493;2433.148398060682;2435.476372579904;9584.424934503882;9591.712248632552;9666.033396660348;9678.95160524193;9709.126178643955;5565.026120428909;5772.913406298909;5778.739956701958;5781.075027995543;5782.595218334718;3562.608113107556;3598.6941110005682;3610.8916732497805;3644.1982763791925;3645.872394466181;1135.4606561883122;1150.654803558929;1206.3485717712513;1224.6326102169403;2307.457747429353;13658.658533536642;14292.928535357361;14293.642658933506;14308.141511509342;14319.78520322196;4884.584810291094;4951.207806750903;5140;5245.7114858683135;5285.154375299933;11000;11089.112870970303;11108.888911110786;11109.55561777536;11125.054370378446;5740.225069615549;5744.741486633112;5746.166805813217;5748.9712551437815;5753.798617048383;4219.261629214937;4511.616512596466;5100.719460429712;5167.0957582422925;5342.919856202082;4208.494980602347;4220.198162349144;4308.633766129851;4338.34924761282;4344.23975804233;1228.0605336917265;1266.7466506698697;1276.7765641012786;1277.9744405111846;1280.6542233597063;1019.7399663086014;1065.4086981725218;1073.8603981482513;1078.0890147825075;1080.36258607422;6644.66776661165;6760.7295708172105;6864.828379290458;6998.68505917235;7031.261717519586;426.88687497813106;450.63498566161513;455.92021396255524;456.2631350369196;463.64299489392977;4496.033352829158;4514.164879497303;4612.7924243409125;4683.1336202802595;4716.716996980193;12239.694007649701;12673.746525069448;12739.299338536284;12752.426140823685;12794;11793;12314;12316.630501084981;12697.809532857014;12721.55474558386;8967.417117887317;9131.680391186144;9146.63413463454;9182.127697868691;9222.95388523053;6779.118714567075;6901.447884169354;6976.790696279119;6981.2669669683955;7018.087648605853;11432.542698291974;11723.179377443497;11788.469518871843;11792.76414471723;11812.350320732443;11503.769924601294;11600.88399115998;11812.055035596959;11984.161108722306;11991.400429978292;8138.267555919995;8324.042735085543;8687.39188256816;8803.251723603309;8820.2061814437;9618;9763.072508111718;9771.071748183911;9777.951110244403;9778.168855647065;8032.076311224284;8287.95856020716;8372.916270837457;8561.700340488052;8777.385583009129;8137.308328792119;8275.54484503358;8348.081711011906;8360;8515.701950431705;8041.718539851077;8073.434859559775;8076.27313541784;8135.959320203361;8174.591270436335;5941;5953.613015154001;5955.791547295824;6046.123312119806;6093.969530152321;5882.823515294377;5890.440408161217;5919.289685237799;5975.611585246945;5997.820065398045 03:59:09 INFO - PROCESS | 10886 | |4;prototype.html;335.45153673743425;337.6454722541331;338.65118927504665;340.25144681700266;346.9895903122907;5407.485519233555;5487.149491828768;5493.999999999995;5511.255980442639;5529.894021195767;5150.587952963763;5290.232916227144;5291.788328466857;5310.0972834618115;5435.157550579666;4216.367544868263;4261.403403523509;4294.355846623001;4311.91376172478;4313.396124542566;4274.97862510687;4296.355546667994;4299.290617048183;4304.332828411601;4310.82756689732;4062.9593704063022;4200.390943313218;4230.894227644319;4252.298370768819;4310.612044915957;4214.4099826024385;4228.978855105735;4244.299690551055;4252.277112890816;4262.1688770689725;3751.4372844073337;3754.511913451261;3756.7745935243975;3764.985732633028;3769.5476542815036;5233.528982391565;5252.737363131866;5352.795620985247;5404.270423492818;5404.6486978346675;4823.1800593899125;4833.815715149775;4841.564259216688;4852.757362131879;4862.562369386772;5611.66330020196;5630.662160270397;5676.403977582377;5688;5747.712614369307;3878.146807702301;4070.1859628074208;4080.1431699343175;4110.157417729359;4116.156187981486;5843.883122337572;5896.439838215363;5903.763849446017;5906.763729450817;5908.231929849092;4172.937405938914;4178.247915375201;4183.723964190951;4185.476815398075;4195.685323600742;4164.6251837334785;4224.155168966219;4228.217779710763;4264.424302719093;4269.701120921506;4812.663113582085;4819.132556139928;4823.517648235219;4835;4845.176320025604;5719.19931209631;5727.284089488814;5742.454466825645;5763.769449222068;5802.274715660543;1414.1797757300742;1450.4488294448042;1451.8693317601467;1465.8241011078526;1467.7211329847296;916.8716379706847;929.2658799548282;943.5376665434026;956.9282303827101;960.4669408478362;4874.488178741252;4883.316335713003;4982.252662100642;4989.505247376312;4989.650724449326;5095.949040509621;5120.92318615221;5123.3339665843205;5160.490866211013;5204.947950520522;4942.555170034714;4953.133201689762;4970.254461830682;4982.227754698028;5261.447548007404;3082.5530298107096;3137.811731296084;3149.2756665966954;3153.7477001839798;3164.715175634204;6166.784162554289;6180.66006369645;6188.319284878659;6203.16257305267;6239.282482514478;5839.7225082217965;5857.7949771757785;5911.556633252438;5972.283326000907;5976.701164941735;6028.156058151862;6031.396860313934;6067.393260673985;6070.2412198475195;6132.7546898124465;5578.497935185853;5870.647761134345;5872.794452194153;5879.412058794086;5948.078047902582;5831.854203644943;5869.325027621878;5870.911936321044;5881.882362352643;5884.882302353843;6175.84560385994;6178.876422471435;6206;6207.410296021871;6219.75604879035;4082.857100001486;4108.568600296926;4242.512111107262;4271.487421509314;4314.158739045982;5719.5138413235345;5727.680289482766;5735.369109398046;5817.214676018683;5951.256092988377;5758.942410575841;5795.246617939641;5807.245058142415;5811.912821307599;5831.387704291158;5650.40670729584;5685.800996965087;5699.943000569941;5741.8564535886935;5839.824805255849;5651.463111004448;5667.688277144796;5694.544436445157;5713.685747283939;5728.598998070095;5669.234653321913;5787.884242315215;5808.7095645218415;5842.824715258549;5846.56150788684;5655.529562313745;5698.715064246689;5704.229928959538;5710.914336285042;5710.942890571041 03:59:09 INFO - PROCESS | 10886 | |5;yui.html;845.7293666026874;854.7820305822011;900.4237288135595;905.8460061789497;940.3793496292449;16100.75848862268;16789.832101678978;16839.41062062828;16979;17514.474565763016;16249.431269905555;16391.672166556662;16485.505434836945;16494.092824894626;16527;739.6117038554748;883.1036497954581;901.2249465459699;917.4495302818289;920.1580553793272;902.9864552031725;906.2478143141177;911.2118017914522;911.3301723233418;911.7720569857536;966.246327864267;971.4122955611859;972.5915115651443;974.0648976982105;974.1865542272211;769.2691942654473;920.2453987730064;931.7158266728645;965.5606698951996;966.4877614864133;692.5083190934442;692.6017539914569;693.0435998322283;693.0643631097977;693.0851276315266;16180.433684821093;16200.594985125348;16309.673806523802;16325;16334.83665163357;15087.773683394758;15153;15600.60998475036;15609.063456192665;16095.839041609552;13356.599302020955;13460.326983650877;13551.39018744159;13591.32043397836;13915.373808178656;4766.428028636586;4866.221404575251;4891.633127515451;4965.751712414401;4978.502149785028;85154.57422712847;85855.57072214661;86489;87101;88963;11990.280583165038;12046.698832529082;12178.269303841798;12243;12368.1960672556;23246;23365.532689346117;23450.06199752025;23570.175043873383;23578.646320305208;9060.320475964329;9092.954535227282;9140.223081038053;9171.678991235409;9232.53837308132;5022.522860328263;5177.145770947826;5296.92054619181;5317.069512835315;5332.653377530526;3605.5673319201337;3669.192777588926;3698.0385099874225;3719.5350581177354;3720.2931443025736;638.9552731308763;648.3289795062088;648.7761722205831;655.7540922154192;657.1096164696767;638.3743930947697;643.2602507116853;671.057905962814;708.4544900426642;763.5876626621673;9521.23830093591;9596.136347728598;9732.318737688294;9801.31390802637;10196.541155648014;614.2015380005975;654.7871941618993;710.6553321639001;712.8859382498879;741.1328745367932;4593.563611456906;4891.874245691784;4933.68673784674;4969;4976.4525902150735;127820.36089819677;130804;131080;133443;137175;107551.46224268986;108080.45959770151;108985.45507272413;110666;112550.99999999836;97058;98146.5092674532;100596.49701751591;101961.49019255005;102677.9999999985;9582.233421326417;9824.705258842247;9866.506674666094;9881.456519891475;9905.15806156456;58829.41170588239;58875.41124588699;59075.704621476616;59095.70452147712;59246.40753592409;78537.60731196307;84925.15074849172;85925.57037214773;88389;92559.53720231356;11537.942310288396;12165.45255463524;12210.145289829981;12465.189762665397;12771.616851494098;14272.429102835771;14281.857181428053;14456.855431445972;14986.250687465801;15342.386304547694;11199.888001119885;13331.866681333062;14191.290435478391;15047.924760376565;15297.930690600848;14864.554063378117;15094.698106038037;15573.922130389275;15627.21863906823;15950.441734539236;14319.57041288763;14771.261436927896;14896.329665164876;14927.925360373129;15255.847441525886;11112.833307500556;11292.774144517229;11377.4880130396;11454.083673306282;11743.76512469763;10952;10988.450577471254;11226.55093796239;12027.819582706092;12218.327991960528 03:59:09 INFO - PROCESS | 10886 | __end_tp_report 03:59:09 INFO - PROCESS | 10886 | __start_cc_report 03:59:09 INFO - PROCESS | 10886 | _x_x_mozilla_cycle_collect,1255 03:59:09 INFO - PROCESS | 10886 | __end_cc_report 03:59:09 INFO - PROCESS | 10886 | __startTimestamp1471604349717__endTimestamp 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | ------- Summary: start ------- 03:59:09 INFO - PROCESS | 10886 | Number of tests: 6 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | [#0] dojo.html Cycles:165 Average:5623.73 Median:5818.19 stddev:632.18 (10.9%) stddev-sans-first:605.81 03:59:09 INFO - PROCESS | 10886 | Values: 3239.4 3251.0 3258.8 3263.3 3295.8 5520.1 5595.9 5599.6 5621.6 5624.6 5668.5 5729.1 5731.4 5740.7 5773.2 5027.4 5111.3 5165.6 5199.2 5252.8 5187.2 5229.9 5233.6 5234.2 5234.8 5107.6 5141.8 5152.1 5161.1 5222.2 5236.5 5262.8 5264.6 5275.0 5302.9 5038.5 5062.7 5113.1 5135.1 5162.6 5587.3 5596.9 5597.6 5631.8 5637.4 6105.9 6112.2 6117.4 6118.3 6120.6 5896.9 5979.5 6014.8 6064.5 6139.2 5710.3 5723.0 5734.9 5735.4 5746.4 6209.4 6232.6 6232.8 6237.4 6244.4 5830.7 5921.3 5924.8 5933.6 6046.4 6075.1 6085.8 6121.6 6123.1 6143.6 5991.2 6032.8 6042.8 6056.9 6063.9 5694.0 5956.2 6009.2 6017.3 6050.2 4617.1 4644.2 4646.2 4652.2 4678.9 4114.1 4125.2 4131.1 4159.4 4160.4 5612.8 5741.5 5762.0 5776.2 5787.4 6109.7 6110.8 6116.4 6119.2 6120.7 5629.5 5810.7 5818.2 5825.3 5842.4 5150.6 5191.5 5209.7 5258.5 5264.6 6270.8 6301.8 6312.9 6317.8 6317.8 6014.2 6051.1 6068.3 6070.4 6111.1 6120.6 6133.0 6135.4 6137.6 6165.4 5888.0 5903.0 5903.7 5904.1 5945.1 5999.3 6005.0 6010.9 6019.3 6023.8 5800.9 5826.9 5873.3 5951.3 5952.4 5923.2 5923.5 5930.1 5936.6 5942.1 5704.3 5740.5 5753.6 5827.2 5852.7 5915.3 5920.2 5948.8 5964.5 5972.4 5764.9 5767.5 5772.9 5778.0 5781.1 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | [#1] ext.html Cycles:180 Average:64682.14 Median:40680.99 stddev:130007.81 (319.6%) stddev-sans-first:130283.50 03:59:09 INFO - PROCESS | 10886 | Values: 606.1 647.9 668.7 673.7 679.0 34903.8 45282.0 47161.8 47182.5 47272.0 48178.0 48750.0 48772.3 48852.3 48877.3 9233.8 9366.7 9370.3 9374.1 9376.3 8474.5 10916.7 11190.0 11200.8 11572.5 11240.9 11414.8 11416.5 11420.7 11421.4 11172.0 11185.4 11195.7 11198.2 11222.5 11094.2 11097.4 11166.3 11181.6 11186.4 45924.0 46063.3 46165.0 46185.5 46235.0 3768.7 3869.7 4181.7 4183.8 4567.6 34633.4 35135.3 35181.1 35424.3 35701.5 4527.1 4533.0 4552.9 4580.4 4597.3 360194.0 360751.0 361091.0 362451.0 362953.0 65308.7 65411.0 65703.7 65756.7 65808.0 39251.6 40379.6 40651.4 42473.4 42533.8 17190.7 17284.9 17313.0 17338.1 17339.9 11669.9 12928.1 12936.9 12989.0 12998.3 20564.9 21043.6 21166.3 21231.3 21237.8 7702.3 7860.1 7909.6 7927.3 7974.2 1004.4 1064.4 1152.8 1173.2 1228.5 34605.7 35007.0 35072.6 35089.3 35347.0 1195.3 1202.3 1215.7 1244.1 1260.7 17312.7 17372.8 17531.4 17879.8 18060.3 640213.0 645294.0 646107.0 646674.0 647182.0 64843.4 65348.0 65350.7 65416.0 65586.0 1350.5 1392.5 1454.1 1483.6 1489.0 31666.4 36345.0 36385.3 36694.4 36727.4 54377.2 55510.0 55510.7 55535.4 55561.4 410071.0 412016.0 414197.0 414428.0 415031.0 27826.0 28259.4 29228.9 31584.4 33346.7 52788.2 53105.5 53306.0 53763.5 54319.0 44546.6 45401.0 45846.3 46016.6 46592.3 44813.6 46356.0 46765.8 48809.5 49143.3 44621.0 45489.0 45609.5 45860.8 45990.5 39163.0 40710.6 40831.0 40908.8 41919.6 30153.2 30474.4 30717.7 30740.5 30985.8 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | [#2] jquery.html Cycles:180 Average:31940.55 Median:12734.33 stddev:95840.16 (752.6%) stddev-sans-first:96079.74 03:59:09 INFO - PROCESS | 10886 | Values: 393.7 393.8 395.9 402.5 423.8 14105.0 20255.3 20291.1 20307.8 20696.9 11855.9 12330.4 12340.1 12354.7 12386.9 4040.5 5312.3 5476.8 5486.3 5781.5 6019.7 6042.3 6113.8 6118.9 6124.2 5852.9 5857.2 5888.1 5900.2 6102.8 5819.4 5937.3 5945.9 5953.9 5969.6 5741.1 5850.7 5852.5 5853.2 5855.2 13654.8 13742.5 19833.8 23332.4 23509.2 2357.7 2635.5 2642.8 2693.8 2765.5 10222.8 10462.8 10619.0 10677.8 10752.6 1923.2 1964.8 2043.1 2086.6 2136.3 82689.6 85301.0 86360.6 87571.0 89063.6 11439.3 11800.5 11873.3 11897.0 11972.8 16696.2 16697.4 16714.8 16719.3 16731.2 8414.9 8556.4 8680.0 8686.7 8743.0 8267.7 8291.9 8301.8 8450.9 8492.4 4601.6 5027.4 5054.3 5117.9 5119.8 1961.4 2056.6 2093.9 2096.7 2119.7 961.3 1021.4 1025.6 1069.4 1086.9 19326.8 20991.6 20998.1 21114.8 21310.0 1019.5 1048.5 1078.9 1085.0 1141.2 10863.8 11661.6 11712.2 11797.5 11827.6 574240.0 576347.0 581932.0 584084.0 597093.0 91881.0 94196.5 95006.5 96857.0 97907.0 993.8 1008.1 1021.8 1033.4 1058.7 17829.3 18291.4 18424.1 18478.6 18725.5 41575.6 43846.3 44339.0 44435.3 44609.3 53986.0 63175.4 64076.0 65347.3 65446.7 8296.3 8421.0 8475.8 8674.7 8740.2 10212.3 12756.6 13760.2 13951.5 14105.6 11369.5 11962.8 12240.8 12278.1 12297.8 11864.8 11972.8 12244.9 12255.9 12409.7 11950.6 12178.9 12411.1 12427.1 12545.9 8247.9 8413.5 8440.5 8569.0 8906.3 10602.7 12175.0 12280.8 12337.5 12583.1 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | [#3] mootools.html Cycles:180 Average:6453.23 Median:2485.04 stddev:3791.86 (152.6%) stddev-sans-first:3783.68 03:59:09 INFO - PROCESS | 10886 | Values: 1426.5 1495.9 1581.4 1731.2 1898.2 8455.3 9263.0 9534.6 9541.8 9785.2 9486.1 9546.2 9548.6 9559.9 9563.1 2478.9 2505.6 2506.9 2507.5 2507.9 2288.9 2504.6 2506.7 2506.8 2507.7 2443.1 2459.8 2511.0 2512.2 2514.6 2399.2 2424.9 2439.8 2441.1 2448.4 2397.8 2425.9 2426.4 2433.1 2435.5 9584.4 9591.7 9666.0 9679.0 9709.1 5565.0 5772.9 5778.7 5781.1 5782.6 3562.6 3598.7 3610.9 3644.2 3645.9 1135.5 1150.7 1206.3 1224.6 2307.5 13658.7 14292.9 14293.6 14308.1 14319.8 4884.6 4951.2 5140.0 5245.7 5285.2 11000.0 11089.1 11108.9 11109.6 11125.1 5740.2 5744.7 5746.2 5749.0 5753.8 4219.3 4511.6 5100.7 5167.1 5342.9 4208.5 4220.2 4308.6 4338.3 4344.2 1228.1 1266.7 1276.8 1278.0 1280.7 1019.7 1065.4 1073.9 1078.1 1080.4 6644.7 6760.7 6864.8 6998.7 7031.3 426.9 450.6 455.9 456.3 463.6 4496.0 4514.2 4612.8 4683.1 4716.7 12239.7 12673.7 12739.3 12752.4 12794.0 11793.0 12314.0 12316.6 12697.8 12721.6 8967.4 9131.7 9146.6 9182.1 9223.0 6779.1 6901.4 6976.8 6981.3 7018.1 11432.5 11723.2 11788.5 11792.8 11812.4 11503.8 11600.9 11812.1 11984.2 11991.4 8138.3 8324.0 8687.4 8803.3 8820.2 9618.0 9763.1 9771.1 9778.0 9778.2 8032.1 8288.0 8372.9 8561.7 8777.4 8137.3 8275.5 8348.1 8360.0 8515.7 8041.7 8073.4 8076.3 8136.0 8174.6 5941.0 5953.6 5955.8 6046.1 6094.0 5882.8 5890.4 5919.3 5975.6 5997.8 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | [#4] prototype.html Cycles:180 Average:4780.23 Median:5331.45 stddev:1409.70 (26.4%) stddev-sans-first:1373.61 03:59:09 INFO - PROCESS | 10886 | Values: 335.5 337.6 338.7 340.3 347.0 5407.5 5487.1 5494.0 5511.3 5529.9 5150.6 5290.2 5291.8 5310.1 5435.2 4216.4 4261.4 4294.4 4311.9 4313.4 4275.0 4296.4 4299.3 4304.3 4310.8 4063.0 4200.4 4230.9 4252.3 4310.6 4214.4 4229.0 4244.3 4252.3 4262.2 3751.4 3754.5 3756.8 3765.0 3769.5 5233.5 5252.7 5352.8 5404.3 5404.6 4823.2 4833.8 4841.6 4852.8 4862.6 5611.7 5630.7 5676.4 5688.0 5747.7 3878.1 4070.2 4080.1 4110.2 4116.2 5843.9 5896.4 5903.8 5906.8 5908.2 4172.9 4178.2 4183.7 4185.5 4195.7 4164.6 4224.2 4228.2 4264.4 4269.7 4812.7 4819.1 4823.5 4835.0 4845.2 5719.2 5727.3 5742.5 5763.8 5802.3 1414.2 1450.4 1451.9 1465.8 1467.7 916.9 929.3 943.5 956.9 960.5 4874.5 4883.3 4982.3 4989.5 4989.7 5095.9 5120.9 5123.3 5160.5 5204.9 4942.6 4953.1 4970.3 4982.2 5261.4 3082.6 3137.8 3149.3 3153.7 3164.7 6166.8 6180.7 6188.3 6203.2 6239.3 5839.7 5857.8 5911.6 5972.3 5976.7 6028.2 6031.4 6067.4 6070.2 6132.8 5578.5 5870.6 5872.8 5879.4 5948.1 5831.9 5869.3 5870.9 5881.9 5884.9 6175.8 6178.9 6206.0 6207.4 6219.8 4082.9 4108.6 4242.5 4271.5 4314.2 5719.5 5727.7 5735.4 5817.2 5951.3 5758.9 5795.2 5807.2 5811.9 5831.4 5650.4 5685.8 5699.9 5741.9 5839.8 5651.5 5667.7 5694.5 5713.7 5728.6 5669.2 5787.9 5808.7 5842.8 5846.6 5655.5 5698.7 5704.2 5710.9 5710.9 03:59:09 INFO - PROCESS | 10886 | 03:59:09 INFO - PROCESS | 10886 | [#5] yui.html Cycles:180 Average:23229.82 Median:4816.32 stddev:34283.41 (711.8%) stddev-sans-first:34338.38 03:59:09 INFO - PROCESS | 10886 | Values: 845.7 854.8 900.4 905.8 940.4 16100.8 16789.8 16839.4 16979.0 17514.5 16249.4 16391.7 16485.5 16494.1 16527.0 739.6 883.1 901.2 917.4 920.2 903.0 906.2 911.2 911.3 911.8 966.2 971.4 972.6 974.1 974.2 769.3 920.2 931.7 965.6 966.5 692.5 692.6 693.0 693.1 693.1 16180.4 16200.6 16309.7 16325.0 16334.8 15087.8 15153.0 15600.6 15609.1 16095.8 13356.6 13460.3 13551.4 13591.3 13915.4 4766.4 4866.2 4891.6 4965.8 4978.5 85154.6 85855.6 86489.0 87101.0 88963.0 11990.3 12046.7 12178.3 12243.0 12368.2 23246.0 23365.5 23450.1 23570.2 23578.6 9060.3 9093.0 9140.2 9171.7 9232.5 5022.5 5177.1 5296.9 5317.1 5332.7 3605.6 3669.2 3698.0 3719.5 3720.3 639.0 648.3 648.8 655.8 657.1 638.4 643.3 671.1 708.5 763.6 9521.2 9596.1 9732.3 9801.3 10196.5 614.2 654.8 710.7 712.9 741.1 4593.6 4891.9 4933.7 4969.0 4976.5 127820.4 130804.0 131080.0 133443.0 137175.0 107551.5 108080.5 108985.5 110666.0 112551.0 97058.0 98146.5 100596.5 101961.5 102678.0 9582.2 9824.7 9866.5 9881.5 9905.2 58829.4 58875.4 59075.7 59095.7 59246.4 78537.6 84925.2 85925.6 88389.0 92559.5 11537.9 12165.5 12210.1 12465.2 12771.6 14272.4 14281.9 14456.9 14986.3 15342.4 11199.9 13331.9 14191.3 15047.9 15297.9 14864.6 15094.7 15573.9 15627.2 15950.4 14319.6 14771.3 14896.3 14927.9 15255.8 11112.8 11292.8 11377.5 11454.1 11743.8 10952.0 10988.5 11226.6 12027.8 12218.3 03:59:09 INFO - PROCESS | 10886 | -------- Summary: end -------- 03:59:09 INFO - PROCESS | 10886 | 03:59:10 INFO - TEST-INFO | 10886: exit 0 03:59:10 INFO - TEST-OK | dromaeo_css | took 1113722ms 03:59:10 INFO - TEST-START | kraken 03:59:10 INFO - operating with platform_type : linux_ 03:59:10 INFO - Initialising browser for kraken test... 03:59:10 INFO - TEST-INFO | started process 11085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCAEI_D/profile http://localhost:33882/getInfo.html) 03:59:14 INFO - PROCESS | 11085 | __metrics Screen width/height:1600/1200 03:59:14 INFO - PROCESS | 11085 | colorDepth:24 03:59:14 INFO - PROCESS | 11085 | Browser inner width/height: 1024/697 03:59:14 INFO - PROCESS | 11085 | __metrics 03:59:15 INFO - PROCESS | 11085 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:59:15 INFO - PROCESS | 11085 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:59:16 INFO - TEST-INFO | 11085: exit 0 03:59:16 INFO - Browser initialized. 03:59:16 INFO - Running cycle 1/1 for kraken test... 03:59:16 INFO - TEST-INFO | started process 11174 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCAEI_D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:59:28 INFO - PROCESS | 11174 | RSS: Main: 144777216 03:59:28 INFO - PROCESS | 11174 | 03:59:30 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33882/tests/kraken/driver.html?testName=audio-beat-detection) 03:59:31 INFO - PROCESS | 11174 | RSS: Main: 191827968 03:59:31 INFO - PROCESS | 11174 | 03:59:34 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33882/tests/kraken/driver.html?testName=audio-dft) 03:59:34 INFO - PROCESS | 11174 | RSS: Main: 182009856 03:59:34 INFO - PROCESS | 11174 | 03:59:37 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33882/tests/kraken/driver.html?testName=audio-fft) 03:59:38 INFO - PROCESS | 11174 | RSS: Main: 269176832 03:59:38 INFO - PROCESS | 11174 | 03:59:40 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33882/tests/kraken/driver.html?testName=audio-oscillator) 03:59:40 INFO - PROCESS | 11174 | RSS: Main: 175722496 03:59:40 INFO - PROCESS | 11174 | 03:59:43 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33882/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:59:43 INFO - PROCESS | 11174 | RSS: Main: 177524736 03:59:43 INFO - PROCESS | 11174 | 03:59:46 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33882/tests/kraken/driver.html?testName=imaging-darkroom) 03:59:47 INFO - PROCESS | 11174 | RSS: Main: 212873216 03:59:47 INFO - PROCESS | 11174 | 03:59:50 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33882/tests/kraken/driver.html?testName=imaging-desaturate) 03:59:51 INFO - PROCESS | 11174 | RSS: Main: 211898368 03:59:51 INFO - PROCESS | 11174 | 03:59:54 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33882/tests/kraken/driver.html?testName=json-parse-financial) 03:59:54 INFO - PROCESS | 11174 | RSS: Main: 211812352 03:59:54 INFO - PROCESS | 11174 | 03:59:56 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33882/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:59:56 INFO - PROCESS | 11174 | RSS: Main: 176275456 03:59:56 INFO - PROCESS | 11174 | 03:59:58 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:59:59 INFO - PROCESS | 11174 | RSS: Main: 183058432 03:59:59 INFO - PROCESS | 11174 | 04:00:01 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:00:01 INFO - PROCESS | 11174 | RSS: Main: 186769408 04:00:01 INFO - PROCESS | 11174 | 04:00:04 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:00:04 INFO - PROCESS | 11174 | RSS: Main: 180330496 04:00:04 INFO - PROCESS | 11174 | 04:00:07 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:00:08 INFO - PROCESS | 11174 | RSS: Main: 184221696 04:00:08 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | Cycle 1(1): loaded http://localhost:33882/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33882/tests/kraken/driver.html?testName=ai-astar) 04:00:09 INFO - PROCESS | 11174 | RSS: Main: 235802624 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | __start_tp_report 04:00:09 INFO - PROCESS | 11174 | _x_x_mozilla_page_load 04:00:09 INFO - PROCESS | 11174 | _x_x_mozilla_page_load_details 04:00:09 INFO - PROCESS | 11174 | |i|pagename|runs| 04:00:09 INFO - PROCESS | 11174 | |0;ai-astar;100;111;110;117;112;109;109;109;108;108 04:00:09 INFO - PROCESS | 11174 | |1;audio-beat-detection;145;114;117;172;117;117;140;115;162;118 04:00:09 INFO - PROCESS | 11174 | |2;audio-dft;161;161;161;160;161;163;160;161;159;160 04:00:09 INFO - PROCESS | 11174 | |3;audio-fft;74;74;74;109;75;76;75;75;99;74 04:00:09 INFO - PROCESS | 11174 | |4;audio-oscillator;83;83;124;83;84;83;119;82;84;129 04:00:09 INFO - PROCESS | 11174 | |5;imaging-gaussian-blur;116;116;117;116;117;118;119;118;118;116 04:00:09 INFO - PROCESS | 11174 | |6;imaging-darkroom;147;146;146;146;145;146;146;147;146;146 04:00:09 INFO - PROCESS | 11174 | |7;imaging-desaturate;95;95;95;95;95;96;95;95;95;95 04:00:09 INFO - PROCESS | 11174 | |8;json-parse-financial;53;51;63;50;51;52;52;50;51;50 04:00:09 INFO - PROCESS | 11174 | |9;json-stringify-tinderbox;58;59;58;59;59;59;59;59;59;59 04:00:09 INFO - PROCESS | 11174 | |10;stanford-crypto-aes;73;72;82;72;71;81;82;78;75;75 04:00:09 INFO - PROCESS | 11174 | |11;stanford-crypto-ccm;116;123;127;126;116;116;120;124;193;128 04:00:09 INFO - PROCESS | 11174 | |12;stanford-crypto-pbkdf2;150;157;148;148;147;150;152;149;148;153 04:00:09 INFO - PROCESS | 11174 | |13;stanford-crypto-sha256-iterative;63;63;61;60;60;62;61;60;60;61 04:00:09 INFO - PROCESS | 11174 | __end_tp_report 04:00:09 INFO - PROCESS | 11174 | __start_cc_report 04:00:09 INFO - PROCESS | 11174 | _x_x_mozilla_cycle_collect,1631 04:00:09 INFO - PROCESS | 11174 | __end_cc_report 04:00:09 INFO - PROCESS | 11174 | __startTimestamp1471604409917__endTimestamp 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | ------- Summary: start ------- 04:00:09 INFO - PROCESS | 11174 | Number of tests: 14 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#0] ai-astar Cycles:10 Average:109.30 Median:109.50 stddev:4.22 (3.9%) stddev-sans-first:2.83 04:00:09 INFO - PROCESS | 11174 | Values: 100.0 111.0 110.0 117.0 112.0 109.0 109.0 109.0 108.0 108.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:129.00 stddev:21.64 (16.8%) stddev-sans-first:22.42 04:00:09 INFO - PROCESS | 11174 | Values: 145.0 114.0 117.0 172.0 117.0 117.0 140.0 115.0 162.0 118.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 04:00:09 INFO - PROCESS | 11174 | Values: 161.0 161.0 161.0 160.0 161.0 163.0 160.0 161.0 159.0 160.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#3] audio-fft Cycles:10 Average:80.50 Median:75.00 stddev:12.62 (16.8%) stddev-sans-first:13.17 04:00:09 INFO - PROCESS | 11174 | Values: 74.0 74.0 74.0 109.0 75.0 76.0 75.0 75.0 99.0 74.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:83.00 stddev:19.88 (24.0%) stddev-sans-first:20.58 04:00:09 INFO - PROCESS | 11174 | Values: 83.0 83.0 124.0 83.0 84.0 83.0 119.0 82.0 84.0 129.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.50 stddev:1.10 (0.9%) stddev-sans-first:1.09 04:00:09 INFO - PROCESS | 11174 | Values: 116.0 116.0 117.0 116.0 117.0 118.0 119.0 118.0 118.0 116.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 04:00:09 INFO - PROCESS | 11174 | Values: 147.0 146.0 146.0 146.0 145.0 146.0 146.0 147.0 146.0 146.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:00:09 INFO - PROCESS | 11174 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:51.50 stddev:3.89 (7.6%) stddev-sans-first:4.12 04:00:09 INFO - PROCESS | 11174 | Values: 53.0 51.0 63.0 50.0 51.0 52.0 52.0 50.0 51.0 50.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 04:00:09 INFO - PROCESS | 11174 | Values: 58.0 59.0 58.0 59.0 59.0 59.0 59.0 59.0 59.0 59.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.50 stddev:4.33 (5.7%) stddev-sans-first:4.45 04:00:09 INFO - PROCESS | 11174 | Values: 73.0 72.0 82.0 72.0 71.0 81.0 82.0 78.0 75.0 75.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#11] stanford-crypto-ccm Cycles:10 Average:128.90 Median:125.00 stddev:22.99 (18.4%) stddev-sans-first:23.91 04:00:09 INFO - PROCESS | 11174 | Values: 116.0 123.0 127.0 126.0 116.0 116.0 120.0 124.0 193.0 128.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.20 Median:150.00 stddev:3.05 (2.0%) stddev-sans-first:3.23 04:00:09 INFO - PROCESS | 11174 | Values: 150.0 157.0 148.0 148.0 147.0 150.0 152.0 149.0 148.0 153.0 04:00:09 INFO - PROCESS | 11174 | 04:00:09 INFO - PROCESS | 11174 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.20 (2.0%) stddev-sans-first:1.05 04:00:09 INFO - PROCESS | 11174 | Values: 63.0 63.0 61.0 60.0 60.0 62.0 61.0 60.0 60.0 61.0 04:00:09 INFO - PROCESS | 11174 | -------- Summary: end -------- 04:00:09 INFO - PROCESS | 11174 | 04:00:10 INFO - TEST-INFO | 11174: exit 0 04:00:10 INFO - TEST-OK | kraken | took 60514ms 04:00:10 INFO - SUITE-END | took 1174s 04:00:11 INFO - Completed test suite (00:19:34) 04:00:11 INFO - javascript benchmark 04:00:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3239.36832317698, 3250.9837450812743, 3258.7555933305002, 3263.298390845968, 3295.769296149269, 5520.06158952978, 5595.916061259074, 5599.608027438076, 5621.550275977917, 5624.606277560572, 5668.461496157869, 5729.05470597352, 5731.426857314266, 5740.741666625012, 5773.162891380758, 5027.4469808321055, 5111.309973153614, 5165.63840531163, 5199.220116982464, 5252.842414727565, 5187.221916712486, 5229.86925326868, 5233.607479439038, 5234.162533994561, 5234.7644356004075, 5107.565856902149, 5141.76862041209, 5152.072626927152, 5161.148410512261, 5222.164453687411, 5236.4763523647525, 5262.763175657066, 5264.6051546134195, 5275.024120537693, 5302.946970530284, 5038.5465308122075, 5062.746862656889, 5113.130767769489, 5135.101357262502, 5162.612804039674, 5587.3015873015875, 5596.916046259309, 5597.608167428281, 5631.831045068655, 5637.379888212294, 6105.908411373788, 6112.174856394375, 6117.449429551361, 6118.329570431041, 6120.632762034292, 5896.911546326808, 5979.4618484336615, 6014.789482368096, 6064.454199122056, 6139.2325959255095, 5710.257666503369, 5723.027085395493, 5734.856628584319, 5735.426457354273, 5746.42535746422, 6209.410106039919, 6232.594881332699, 6232.844178895473, 6237.438630523275, 6244.438000579969, 5830.737616807254, 5921.348651648315, 5924.7926322578505, 5933.643981361132, 6046.425589568984, 6075.119107729354, 6085.753803596121, 6121.571489995657, 6123.08153776937, 6143.600665956698, 5991.221141251696, 6032.758689652364, 6042.758289668364, 6056.878862422727, 6063.9090413643835, 5694.0, 5956.195913551702, 6009.1587177795145, 6017.338092809788, 6050.152978583002, 4617.0996655651825, 4644.233701439225, 4646.233371493733, 4652.1626107300335, 4678.883027924261, 4114.136031433432, 4125.23683118618, 4131.132462182975, 4159.355299928516, 4160.417541544186, 5612.831615051555, 5741.483266506035, 5761.971190144106, 5776.249087618667, 5787.363390027093, 6109.726306153001, 6110.820830534146, 6116.42707509198, 6119.228375855631, 6120.721420533169, 5629.549636029108, 5810.680412577264, 5818.185454036438, 5825.271841019872, 5842.444967728059, 5150.639455238173, 5191.506806853343, 5209.661372010807, 5258.474152584788, 5264.6051546134195, 6270.780522681775, 6301.77943771975, 6312.873742525124, 6317.810465686037, 6317.842053948688, 6014.158017877413, 6051.062085376773, 6068.302145253352, 6070.392960704071, 6111.083337499233, 6120.571559990758, 6132.969335153296, 6135.416406698588, 6137.62214577108, 6165.3526379731275, 5887.970560147171, 5902.970485147547, 5903.7048147593305, 5904.055351143968, 5945.10823376497, 5999.310079340931, 6005.039193728982, 6010.879782404415, 6019.277686677627, 6023.849403764941, 5800.9419905800405, 5826.854328641818, 5873.295204575478, 5951.256092988377, 5952.434518720715, 5923.229980102731, 5923.496502797138, 5930.11048342752, 5936.614120082181, 5942.078977758454, 5704.3154821421695, 5740.454656807597, 5753.597248192586, 5827.155062515996, 5852.707364631837, 5915.349311575637, 5920.200772895604, 5948.791792287249, 5964.493018093511, 5972.402759723993, 5764.913526297025, 5767.509761670137, 5772.884542309214, 5778.04662230744, 5781.132830075354], "value": 5581.151883750545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.0817860940675, 647.9254885688146, 668.7458765669045, 673.7170388436856, 679.0459404536628, 34903.82548087259, 45282.0, 47161.764191179, 47182.52817471829, 47272.0, 48178.0, 48750.0, 48772.26841597379, 48852.2672159917, 48877.26684099733, 9233.76915577111, 9366.672166474174, 9370.250379969586, 9374.109459601326, 9376.296777741696, 8474.533900635462, 10916.727081822939, 11190.0, 11200.831987520194, 11572.537098516092, 11240.887591124067, 11414.771704565903, 11416.54333826646, 11420.657380278564, 11421.371824549646, 11172.0, 11185.384803835786, 11195.664130076111, 11198.160137989684, 11222.494987725571, 11094.223404361697, 11097.3896435696, 11166.330020198815, 11181.552737890475, 11186.384748838811, 45924.0, 46063.309050364274, 46165.0, 46185.53814461846, 46235.0, 3768.7361884668085, 3869.7097717671004, 4181.665466762652, 4183.832646694129, 4567.611753000999, 34633.422498374595, 35135.29729405398, 35181.12047198815, 35424.29151416957, 35701.46447803285, 4527.139843429771, 4532.9773351133035, 4552.8861778455475, 4580.358749775034, 4597.310403439477, 360194.0, 360751.0, 361091.0, 362451.0, 362952.9999999974, 65308.673456632416, 65411.0, 65703.67148164229, 65756.67121664456, 65808.0, 39251.60748392536, 40379.59620403817, 40651.390229146586, 42473.36289955591, 42533.78733106315, 17190.74213886793, 17284.91357543204, 17313.0, 17338.13309334528, 17339.913300433418, 11669.941650291694, 12928.095033347763, 12936.935315323362, 12988.960883129326, 12998.285094319903, 20564.897175514325, 21043.579128417347, 21166.259180928595, 21231.256906008217, 21237.787622123582, 7702.268284512962, 7860.135385107634, 7909.604519773989, 7927.265093542718, 7974.202579742096, 1004.3973615830444, 1064.3986147826454, 1152.792497350485, 1173.167051393503, 1228.4778968938344, 34605.65394346075, 35007.0, 35072.64927350694, 35089.298214035574, 35347.0, 1195.3067221011966, 1202.2606097250127, 1215.6778453709774, 1244.1042449436572, 1260.7226410189744, 17312.653746924992, 17372.826271737373, 17531.386401475887, 17879.837810542274, 18060.27758889656, 640213.0, 645294.0, 646107.0, 646674.0, 647182.0, 64843.35156648468, 65348.0, 65350.67324663441, 65416.018759718645, 65586.0, 1350.5340657473157, 1392.463901397969, 1454.1057249791513, 1483.621676472514, 1489.0470099136562, 31666.366672665958, 36345.0, 36385.27229455449, 36694.44958325681, 36727.449088263165, 54377.18434223569, 55510.0, 55510.72244638751, 55535.44464555464, 55561.444385557246, 410071.0, 412016.0, 414197.0, 414428.0, 415031.0, 27826.0, 28259.434811303247, 29228.853855730584, 31584.36831263316, 33346.666533334355, 52788.20817687814, 53105.4689453116, 53306.0, 53763.462365375846, 54319.0, 44546.55453445424, 45401.0, 45846.31230531611, 46016.59585502547, 46592.301115482616, 44813.551864482244, 46356.0, 46765.76617116893, 48809.5119048805, 49143.262851057974, 44621.0, 45489.0, 45609.54390456053, 45860.770696146305, 45990.540094598626, 39163.0, 40710.59289407068, 40831.0, 40908.795456022526, 41919.580804191566, 30153.246168845955, 30474.390512189188, 30717.692823071484, 30740.538891916192, 30985.845070774503], "value": 21891.858118133427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.6830851164811, 393.7971944448608, 395.9208158368326, 402.46271227910745, 423.758457679123, 14105.017367250734, 20255.291064812736, 20291.08690108945, 20307.796922030775, 20696.896515517437, 11855.940720296408, 12330.383480825945, 12340.13619046667, 12354.702756210603, 12386.876131238685, 4040.5353384360883, 5312.256284120216, 5476.835694929158, 5486.31421072366, 5781.53747700183, 6019.72911218994, 6042.335343112276, 6113.816585502442, 6118.908216376758, 6124.234470691164, 5852.882942341151, 5857.238558987347, 5888.057910734284, 5900.1739756434135, 6102.755889764404, 5819.408676209301, 5937.317208521011, 5945.85135371617, 5953.940460595382, 5969.552283578727, 5741.081426971707, 5850.6782126983, 5852.473277405012, 5853.239078919713, 5855.151003104568, 13654.795178072336, 13742.450301987908, 19833.80166198334, 23332.416689582897, 23509.177178798833, 2357.65813956977, 2635.4729054189047, 2642.8150029497942, 2693.824901381404, 2765.5160346939206, 10222.846657300146, 10462.843057354146, 10619.0, 10677.78644427107, 10752.569897204103, 1923.1730355946938, 1964.7642282925997, 2043.1418804102314, 2086.5826834633135, 2136.2523116908956, 82689.58655206746, 85301.0, 86360.56819715924, 87571.0, 89063.55468222682, 11439.313641181556, 11800.52797888092, 11873.287602743863, 11897.0, 11972.820407693891, 16696.165191740365, 16697.415590454275, 16714.83285167157, 16719.331226750794, 16731.247093880804, 8414.894704310736, 8556.443831150955, 8680.0, 8686.652533898574, 8742.956285218661, 8267.710630127916, 8291.917080829235, 8301.8339633207, 8450.915490845136, 8492.447990880573, 4601.631869450436, 5027.396712394536, 5054.292399064134, 5117.923231151536, 5119.795208191631, 1961.3625571689258, 2056.568120694641, 2093.9267125650836, 2096.716943212677, 2119.724435823333, 961.3174646001418, 1021.39737554843, 1025.6153942271649, 1069.3690722473777, 1086.8971272732654, 19326.806731932782, 20991.580168396853, 20998.115348660547, 21114.78885211159, 21310.0, 1019.5157300282307, 1048.533402635835, 1078.8813230544633, 1084.9945750271197, 1141.1612464838338, 10863.782724345469, 11661.591844285409, 11712.238704484182, 11797.52809887595, 11827.645170644895, 574240.0, 576347.0, 581932.0, 584084.0, 597093.0, 91881.0, 94196.52901735448, 95006.5249673761, 96857.0, 97907.0, 993.801239752038, 1008.0725732326277, 1021.8467229915572, 1033.4419413516766, 1058.7194393485731, 17829.286828527234, 18291.359802406852, 18424.078796059875, 18478.6300877175, 18725.531861703566, 41575.58424415717, 43846.34230486609, 44339.0, 44435.33346999862, 44609.330860036476, 53986.0, 63175.368246316946, 64076.0, 65347.34652653413, 65446.672766635864, 8296.294814940798, 8421.0, 8475.788105297417, 8674.739757807276, 8740.21338079576, 10212.314968030489, 12756.61730148097, 13760.17438953666, 13951.511697090958, 14105.6473588157, 11369.4883730234, 11962.820557691815, 12240.816387754368, 12278.07914406441, 12297.815532767194, 11864.76270474603, 11972.820407694066, 12244.938775306422, 12255.938720306698, 12409.689757756129, 11950.581729639422, 12178.939105304416, 12411.131220814455, 12427.130100892851, 12545.87454125447, 8247.917520824954, 8413.495190288602, 8440.535770532433, 8569.0, 8906.287497000354, 10602.681919542427, 12175.0, 12280.81578776337, 12337.50649973991, 12583.119181657195], "value": 9440.446684986458, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.5007247463395, 1495.9027663201894, 1581.4385893007982, 1731.2469075951963, 1898.2312163573758, 8455.32357411407, 9263.0, 9534.570944307505, 9541.809163816719, 9785.21718262539, 9486.146246837783, 9546.188573971222, 9548.618055277799, 9559.904400955988, 9563.091506306908, 2478.9380265493373, 2505.5991041433376, 2506.9373265668323, 2507.4734305795714, 2507.912223072193, 2288.8855557222155, 2504.561701702204, 2506.736792636774, 2506.786923111538, 2507.674002379688, 2443.1204766284213, 2459.8155138364605, 2511.0, 2512.208654273907, 2514.6479492871013, 2399.2442380650127, 2424.903003879843, 2439.8048156147465, 2441.121196369306, 2448.3879030242438, 2397.832151749378, 2425.866577338246, 2426.3691440225493, 2433.148398060682, 2435.476372579904, 9584.424934503882, 9591.712248632552, 9666.033396660348, 9678.95160524193, 9709.126178643955, 5565.026120428909, 5772.913406298909, 5778.739956701958, 5781.075027995543, 5782.595218334718, 3562.608113107556, 3598.6941110005682, 3610.8916732497805, 3644.1982763791925, 3645.872394466181, 1135.4606561883122, 1150.654803558929, 1206.3485717712513, 1224.6326102169403, 2307.457747429353, 13658.658533536642, 14292.928535357361, 14293.642658933506, 14308.141511509342, 14319.78520322196, 4884.584810291094, 4951.207806750903, 5140.0, 5245.7114858683135, 5285.154375299933, 11000.0, 11089.112870970303, 11108.888911110786, 11109.55561777536, 11125.054370378446, 5740.225069615549, 5744.741486633112, 5746.166805813217, 5748.9712551437815, 5753.798617048383, 4219.261629214937, 4511.616512596466, 5100.719460429712, 5167.0957582422925, 5342.919856202082, 4208.494980602347, 4220.198162349144, 4308.633766129851, 4338.34924761282, 4344.23975804233, 1228.0605336917265, 1266.7466506698697, 1276.7765641012786, 1277.9744405111846, 1280.6542233597063, 1019.7399663086014, 1065.4086981725218, 1073.8603981482513, 1078.0890147825075, 1080.36258607422, 6644.66776661165, 6760.7295708172105, 6864.828379290458, 6998.68505917235, 7031.261717519586, 426.88687497813106, 450.63498566161513, 455.92021396255524, 456.2631350369196, 463.64299489392977, 4496.033352829158, 4514.164879497303, 4612.7924243409125, 4683.1336202802595, 4716.716996980193, 12239.694007649701, 12673.746525069448, 12739.299338536284, 12752.426140823685, 12794.0, 11793.0, 12314.0, 12316.630501084981, 12697.809532857014, 12721.55474558386, 8967.417117887317, 9131.680391186144, 9146.63413463454, 9182.127697868691, 9222.95388523053, 6779.118714567075, 6901.447884169354, 6976.790696279119, 6981.2669669683955, 7018.087648605853, 11432.542698291974, 11723.179377443497, 11788.469518871843, 11792.76414471723, 11812.350320732443, 11503.769924601294, 11600.88399115998, 11812.055035596959, 11984.161108722306, 11991.400429978292, 8138.267555919995, 8324.042735085543, 8687.39188256816, 8803.251723603309, 8820.2061814437, 9618.0, 9763.072508111718, 9771.071748183911, 9777.951110244403, 9778.168855647065, 8032.076311224284, 8287.95856020716, 8372.916270837457, 8561.700340488052, 8777.385583009129, 8137.308328792119, 8275.54484503358, 8348.081711011906, 8360.0, 8515.701950431705, 8041.718539851077, 8073.434859559775, 8076.27313541784, 8135.959320203361, 8174.591270436335, 5941.0, 5953.613015154001, 5955.791547295824, 6046.123312119806, 6093.969530152321, 5882.823515294377, 5890.440408161217, 5919.289685237799, 5975.611585246945, 5997.820065398045], "value": 5009.250630253664, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.45153673743425, 337.6454722541331, 338.65118927504665, 340.25144681700266, 346.9895903122907, 5407.485519233555, 5487.149491828768, 5493.999999999995, 5511.255980442639, 5529.894021195767, 5150.587952963763, 5290.232916227144, 5291.788328466857, 5310.0972834618115, 5435.157550579666, 4216.367544868263, 4261.403403523509, 4294.355846623001, 4311.91376172478, 4313.396124542566, 4274.97862510687, 4296.355546667994, 4299.290617048183, 4304.332828411601, 4310.82756689732, 4062.9593704063022, 4200.390943313218, 4230.894227644319, 4252.298370768819, 4310.612044915957, 4214.4099826024385, 4228.978855105735, 4244.299690551055, 4252.277112890816, 4262.1688770689725, 3751.4372844073337, 3754.511913451261, 3756.7745935243975, 3764.985732633028, 3769.5476542815036, 5233.528982391565, 5252.737363131866, 5352.795620985247, 5404.270423492818, 5404.6486978346675, 4823.1800593899125, 4833.815715149775, 4841.564259216688, 4852.757362131879, 4862.562369386772, 5611.66330020196, 5630.662160270397, 5676.403977582377, 5688.0, 5747.712614369307, 3878.146807702301, 4070.1859628074208, 4080.1431699343175, 4110.157417729359, 4116.156187981486, 5843.883122337572, 5896.439838215363, 5903.763849446017, 5906.763729450817, 5908.231929849092, 4172.937405938914, 4178.247915375201, 4183.723964190951, 4185.476815398075, 4195.685323600742, 4164.6251837334785, 4224.155168966219, 4228.217779710763, 4264.424302719093, 4269.701120921506, 4812.663113582085, 4819.132556139928, 4823.517648235219, 4835.0, 4845.176320025604, 5719.19931209631, 5727.284089488814, 5742.454466825645, 5763.769449222068, 5802.274715660543, 1414.1797757300742, 1450.4488294448042, 1451.8693317601467, 1465.8241011078526, 1467.7211329847296, 916.8716379706847, 929.2658799548282, 943.5376665434026, 956.9282303827101, 960.4669408478362, 4874.488178741252, 4883.316335713003, 4982.252662100642, 4989.505247376312, 4989.650724449326, 5095.949040509621, 5120.92318615221, 5123.3339665843205, 5160.490866211013, 5204.947950520522, 4942.555170034714, 4953.133201689762, 4970.254461830682, 4982.227754698028, 5261.447548007404, 3082.5530298107096, 3137.811731296084, 3149.2756665966954, 3153.7477001839798, 3164.715175634204, 6166.784162554289, 6180.66006369645, 6188.319284878659, 6203.16257305267, 6239.282482514478, 5839.7225082217965, 5857.7949771757785, 5911.556633252438, 5972.283326000907, 5976.701164941735, 6028.156058151862, 6031.396860313934, 6067.393260673985, 6070.2412198475195, 6132.7546898124465, 5578.497935185853, 5870.647761134345, 5872.794452194153, 5879.412058794086, 5948.078047902582, 5831.854203644943, 5869.325027621878, 5870.911936321044, 5881.882362352643, 5884.882302353843, 6175.84560385994, 6178.876422471435, 6206.0, 6207.410296021871, 6219.75604879035, 4082.857100001486, 4108.568600296926, 4242.512111107262, 4271.487421509314, 4314.158739045982, 5719.5138413235345, 5727.680289482766, 5735.369109398046, 5817.214676018683, 5951.256092988377, 5758.942410575841, 5795.246617939641, 5807.245058142415, 5811.912821307599, 5831.387704291158, 5650.40670729584, 5685.800996965087, 5699.943000569941, 5741.8564535886935, 5839.824805255849, 5651.463111004448, 5667.688277144796, 5694.544436445157, 5713.685747283939, 5728.598998070095, 5669.234653321913, 5787.884242315215, 5808.7095645218415, 5842.824715258549, 5846.56150788684, 5655.529562313745, 5698.715064246689, 5704.229928959538, 5710.914336285042, 5710.942890571041], "value": 4330.013868569681, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.7293666026874, 854.7820305822011, 900.4237288135595, 905.8460061789497, 940.3793496292449, 16100.75848862268, 16789.832101678978, 16839.41062062828, 16979.0, 17514.474565763016, 16249.431269905555, 16391.672166556662, 16485.505434836945, 16494.092824894626, 16527.0, 739.6117038554748, 883.1036497954581, 901.2249465459699, 917.4495302818289, 920.1580553793272, 902.9864552031725, 906.2478143141177, 911.2118017914522, 911.3301723233418, 911.7720569857536, 966.246327864267, 971.4122955611859, 972.5915115651443, 974.0648976982105, 974.1865542272211, 769.2691942654473, 920.2453987730064, 931.7158266728645, 965.5606698951996, 966.4877614864133, 692.5083190934442, 692.6017539914569, 693.0435998322283, 693.0643631097977, 693.0851276315266, 16180.433684821093, 16200.594985125348, 16309.673806523802, 16325.0, 16334.83665163357, 15087.773683394758, 15153.0, 15600.60998475036, 15609.063456192665, 16095.839041609552, 13356.599302020955, 13460.326983650877, 13551.39018744159, 13591.32043397836, 13915.373808178656, 4766.428028636586, 4866.221404575251, 4891.633127515451, 4965.751712414401, 4978.502149785028, 85154.57422712847, 85855.57072214661, 86489.0, 87101.0, 88963.0, 11990.280583165038, 12046.698832529082, 12178.269303841798, 12243.0, 12368.1960672556, 23246.0, 23365.532689346117, 23450.06199752025, 23570.175043873383, 23578.646320305208, 9060.320475964329, 9092.954535227282, 9140.223081038053, 9171.678991235409, 9232.53837308132, 5022.522860328263, 5177.145770947826, 5296.92054619181, 5317.069512835315, 5332.653377530526, 3605.5673319201337, 3669.192777588926, 3698.0385099874225, 3719.5350581177354, 3720.2931443025736, 638.9552731308763, 648.3289795062088, 648.7761722205831, 655.7540922154192, 657.1096164696767, 638.3743930947697, 643.2602507116853, 671.057905962814, 708.4544900426642, 763.5876626621673, 9521.23830093591, 9596.136347728598, 9732.318737688294, 9801.31390802637, 10196.541155648014, 614.2015380005975, 654.7871941618993, 710.6553321639001, 712.8859382498879, 741.1328745367932, 4593.563611456906, 4891.874245691784, 4933.68673784674, 4969.0, 4976.4525902150735, 127820.36089819677, 130804.0, 131080.0, 133443.0, 137175.0, 107551.46224268986, 108080.45959770151, 108985.45507272413, 110666.0, 112550.99999999836, 97058.0, 98146.5092674532, 100596.49701751591, 101961.49019255005, 102677.9999999985, 9582.233421326417, 9824.705258842247, 9866.506674666094, 9881.456519891475, 9905.15806156456, 58829.41170588239, 58875.41124588699, 59075.704621476616, 59095.70452147712, 59246.40753592409, 78537.60731196307, 84925.15074849172, 85925.57037214773, 88389.0, 92559.53720231356, 11537.942310288396, 12165.45255463524, 12210.145289829981, 12465.189762665397, 12771.616851494098, 14272.429102835771, 14281.857181428053, 14456.855431445972, 14986.250687465801, 15342.386304547694, 11199.888001119885, 13331.866681333062, 14191.290435478391, 15047.924760376565, 15297.930690600848, 14864.554063378117, 15094.698106038037, 15573.922130389275, 15627.21863906823, 15950.441734539236, 14319.57041288763, 14771.261436927896, 14896.329665164876, 14927.925360373129, 15255.847441525886, 11112.833307500556, 11292.774144517229, 11377.4880130396, 11454.083673306282, 11743.76512469763, 10952.0, 10988.450577471254, 11226.55093796239, 12027.819582706092, 12218.327991960528], "value": 8168.212989206156, "unit": "score"}], "name": "dromaeo_css", "value": 7674.888371043853, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 110.0, 117.0, 112.0, 109.0, 109.0, 109.0, 108.0, 108.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 117.0, 172.0, 117.0, 117.0, 140.0, 115.0, 162.0, 118.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 163.0, 160.0, 161.0, 159.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 109.0, 75.0, 76.0, 75.0, 75.0, 99.0, 74.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 124.0, 83.0, 84.0, 83.0, 119.0, 82.0, 84.0, 129.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 118.0, 119.0, 118.0, 118.0, 116.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 63.0, 50.0, 51.0, 52.0, 52.0, 50.0, 51.0, 50.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 82.0, 72.0, 71.0, 81.0, 82.0, 78.0, 75.0, 75.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 123.0, 127.0, 126.0, 116.0, 116.0, 120.0, 124.0, 193.0, 128.0], "value": 128.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 157.0, 148.0, 148.0, 147.0, 150.0, 152.0, 149.0, 148.0, 153.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 60.0, 60.0, 62.0, 61.0, 60.0, 60.0, 61.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1463.3, "alertThreshold": 2.0}]} 04:00:11 INFO - Return code: 0 04:00:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:00:11 INFO - # TBPL SUCCESS # 04:00:11 INFO - Running post-action listener: _resource_record_post_action 04:00:11 INFO - [mozharness: 2016-08-19 11:00:11.262967Z] Finished run-tests step (success) 04:00:11 INFO - Running post-run listener: _resource_record_post_run 04:00:11 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 270336; Write bytes: 326758400; Read time: 100; Write time: 191756 04:00:11 INFO - TinderboxPrint: CPU usage
12.9% 04:00:11 INFO - TinderboxPrint: I/O read bytes / time
270,336 / 100 04:00:11 INFO - TinderboxPrint: I/O write bytes / time
326,758,400 / 191,756 04:00:11 INFO - TinderboxPrint: CPU idle
8,275.1 (87.1%) 04:00:11 INFO - TinderboxPrint: CPU user
1,183.1 (12.4%) 04:00:11 INFO - TinderboxPrint: Swap in / out
0 / 0 04:00:11 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79470592; Read time: 0; Write time: 53840 04:00:11 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 270336; Write bytes: 247287808; Read time: 100; Write time: 137916 04:00:11 INFO - Running post-run listener: _upload_blobber_files 04:00:11 INFO - Blob upload gear active. 04:00:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:00:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:00:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:00:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:00:12 INFO - (blobuploader) - INFO - Open directory for files ... 04:00:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:00:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:12 INFO - (blobuploader) - INFO - Done attempting. 04:00:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:00:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:00:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:16 INFO - (blobuploader) - INFO - Done attempting. 04:00:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:00:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:00:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:00:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:00:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:00:17 INFO - (blobuploader) - INFO - Done attempting. 04:00:17 INFO - (blobuploader) - INFO - Iteration through files over. 04:00:17 INFO - Return code: 0 04:00:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:00:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:00:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75a30b724f0696bbb1adda789d1faa3578fda4c3d9424ad114180a48f29b7b86759731b2f5210f10757fbf3b3338e3193ad6714856b1553cb8efa5e4510dbd59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/001c3c99b8722bf490a49a689c5790b791173cc44ddd264eb8eef519614c5ed6c5fd7e51d32af63a4d39bdceb6415ae87cb5ddff908456b7f6e39703d6458c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad6f8622dcc7f098f2b2f2984f8e01cd269dbee304532bd7b4590d7a6691f161b961d6f18897a4b8e0206b0e757b02a4f288b9b9dfd223a93d4330b458a922bf"} 04:00:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:00:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:00:17 INFO - Contents: 04:00:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75a30b724f0696bbb1adda789d1faa3578fda4c3d9424ad114180a48f29b7b86759731b2f5210f10757fbf3b3338e3193ad6714856b1553cb8efa5e4510dbd59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/001c3c99b8722bf490a49a689c5790b791173cc44ddd264eb8eef519614c5ed6c5fd7e51d32af63a4d39bdceb6415ae87cb5ddff908456b7f6e39703d6458c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad6f8622dcc7f098f2b2f2984f8e01cd269dbee304532bd7b4590d7a6691f161b961d6f18897a4b8e0206b0e757b02a4f288b9b9dfd223a93d4330b458a922bf"} 04:00:17 INFO - Running post-run listener: copy_logs_to_upload_dir 04:00:17 INFO - Copying logs to upload dir... 04:00:17 INFO - mkdir: /builds/slave/test/build/upload/logs 04:00:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.524510 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-19 04:00:17.684966) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 04:00:17.690768) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75a30b724f0696bbb1adda789d1faa3578fda4c3d9424ad114180a48f29b7b86759731b2f5210f10757fbf3b3338e3193ad6714856b1553cb8efa5e4510dbd59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/001c3c99b8722bf490a49a689c5790b791173cc44ddd264eb8eef519614c5ed6c5fd7e51d32af63a4d39bdceb6415ae87cb5ddff908456b7f6e39703d6458c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad6f8622dcc7f098f2b2f2984f8e01cd269dbee304532bd7b4590d7a6691f161b961d6f18897a4b8e0206b0e757b02a4f288b9b9dfd223a93d4330b458a922bf"} build_url:https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://queue.taskcluster.net/v1/task/bf-8i5L8SEKcBddaSxwSuw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/75a30b724f0696bbb1adda789d1faa3578fda4c3d9424ad114180a48f29b7b86759731b2f5210f10757fbf3b3338e3193ad6714856b1553cb8efa5e4510dbd59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/001c3c99b8722bf490a49a689c5790b791173cc44ddd264eb8eef519614c5ed6c5fd7e51d32af63a4d39bdceb6415ae87cb5ddff908456b7f6e39703d6458c23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ad6f8622dcc7f098f2b2f2984f8e01cd269dbee304532bd7b4590d7a6691f161b961d6f18897a4b8e0206b0e757b02a4f288b9b9dfd223a93d4330b458a922bf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 04:00:17.716125) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 04:00:17.716455) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1471595656.905421-1651094664 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 04:00:17.799491) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 04:00:17.799809) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 04:00:17.800103) ========= ========= Total master_lag: 0.16 =========