builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1471517106.2 results: success (0) buildid: 20160818004001 builduid: 282603f8c8c348ccba30882955aafaf3 revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.200402) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.200894) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.201172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.215754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.216107) ========= 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-1471517106.477723-791055313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 03:45:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-08-18 03:45:07 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111558 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.341554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.341886) ========= 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-1471517106.477723-791055313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016313 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.391642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:06.391999) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d23f68f5cb77934e673700bfaaaf607893a2df9b --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 d23f68f5cb77934e673700bfaaaf607893a2df9b --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-1471517106.477723-791055313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 03:45:07,421 truncating revision to first 12 chars 2016-08-18 03:45:07,421 Setting DEBUG logging. 2016-08-18 03:45:07,421 attempt 1/10 2016-08-18 03:45:07,421 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d23f68f5cb77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 03:45:07,729 unpacking tar archive at: mozilla-aurora-d23f68f5cb77/testing/mozharness/ program finished with exit code 0 elapsedTime=0.634893 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:07.041357) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:07.041736) ========= script_repo_revision: d23f68f5cb77934e673700bfaaaf607893a2df9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:07.042154) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:07.042450) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 03:45:07.053764) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-18 03:45:07.054060) ========= /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-1471517106.477723-791055313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:45:08 INFO - MultiFileLogger online at 20160818 03:45:08 in /builds/slave/test 03:45:08 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:45:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:45:08 INFO - {'append_to_log': False, 03:45:08 INFO - 'base_work_dir': '/builds/slave/test', 03:45:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:45:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:45:08 INFO - 'branch': 'Mozilla-Aurora', 03:45:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:45:08 INFO - 'config_files': ('talos/linux_config.py',), 03:45:08 INFO - 'default_actions': ('clobber', 03:45:08 INFO - 'read-buildbot-config', 03:45:08 INFO - 'download-and-extract', 03:45:08 INFO - 'populate-webroot', 03:45:08 INFO - 'create-virtualenv', 03:45:08 INFO - 'install', 03:45:08 INFO - 'run-tests'), 03:45:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:45:08 INFO - 'download_minidump_stackwalk': True, 03:45:08 INFO - 'download_symbols': 'ondemand', 03:45:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:45:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:45:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:45:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:45:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:45:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:45:08 INFO - 'installer_path': 'installer.exe', 03:45:08 INFO - 'log_level': 'info', 03:45:08 INFO - 'log_name': 'talos', 03:45:08 INFO - 'log_to_console': True, 03:45:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:45:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:45:08 INFO - 'opt_config_files': (), 03:45:08 INFO - 'pip_index': False, 03:45:08 INFO - 'sps_profile': False, 03:45:08 INFO - 'sps_profile_interval': 0, 03:45:08 INFO - 'suite': 'dromaeojs', 03:45:08 INFO - 'system_bits': '32', 03:45:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:45:08 INFO - 'title': 'talos-linux64-ix-022', 03:45:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:45:08 INFO - 'use_talos_json': True, 03:45:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:45:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:45:08 INFO - 'work_dir': 'build'} 03:45:08 INFO - [mozharness: 2016-08-18 10:45:08.173157Z] Running clobber step. 03:45:08 INFO - Running pre-action listener: _resource_record_pre_action 03:45:08 INFO - Running main action method: clobber 03:45:08 INFO - rmtree: /builds/slave/test/build 03:45:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:45:08 INFO - Running post-action listener: _resource_record_post_action 03:45:08 INFO - [mozharness: 2016-08-18 10:45:08.661649Z] Finished clobber step (success) 03:45:08 INFO - [mozharness: 2016-08-18 10:45:08.661751Z] Running read-buildbot-config step. 03:45:08 INFO - Running pre-action listener: _resource_record_pre_action 03:45:08 INFO - Running main action method: read_buildbot_config 03:45:08 INFO - Using buildbot properties: 03:45:08 INFO - { 03:45:08 INFO - "project": "", 03:45:08 INFO - "product": "firefox", 03:45:08 INFO - "script_repo_revision": "production", 03:45:08 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:45:08 INFO - "repository": "", 03:45:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:45:08 INFO - "buildid": "20160818004001", 03:45:08 INFO - "stage_platform": "linux64-pgo", 03:45:08 INFO - "basedir": "/builds/slave/test", 03:45:08 INFO - "nightly_build": "True", 03:45:08 INFO - "buildnumber": 684, 03:45:08 INFO - "slavename": "talos-linux64-ix-022", 03:45:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:45:08 INFO - "platform": "ubuntu64_hw", 03:45:08 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:45:08 INFO - "branch": "mozilla-aurora", 03:45:08 INFO - "repo_path": "releases/mozilla-aurora", 03:45:08 INFO - "revision": "d23f68f5cb77934e673700bfaaaf607893a2df9b", 03:45:08 INFO - "pgo_build": "True", 03:45:08 INFO - "builduid": "282603f8c8c348ccba30882955aafaf3", 03:45:08 INFO - "slavebuilddir": "test" 03:45:08 INFO - } 03:45:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:45:08 INFO - Running post-action listener: _resource_record_post_action 03:45:08 INFO - [mozharness: 2016-08-18 10:45:08.664279Z] Finished read-buildbot-config step (success) 03:45:08 INFO - [mozharness: 2016-08-18 10:45:08.664369Z] Running download-and-extract step. 03:45:08 INFO - Running pre-action listener: _resource_record_pre_action 03:45:08 INFO - Running main action method: download_and_extract 03:45:08 INFO - mkdir: /builds/slave/test/build/tests 03:45: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:45:08 INFO - https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:45:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:45:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:45:08 INFO - retry: Failed, sleeping 30 seconds before retrying 03:45:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:45:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:45:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:45:38 INFO - retry: Failed, sleeping 60 seconds before retrying 03:46:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:46:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:46:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:46:38 INFO - Caught exception: HTTP Error 404: Not Found 03:46:38 INFO - Caught exception: HTTP Error 404: Not Found 03:46:38 INFO - Caught exception: HTTP Error 404: Not Found 03:46:38 INFO - trying https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:46:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:46:39 INFO - Downloaded 1361 bytes. 03:46:39 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:46:39 INFO - Using the following test package requirements: 03:46:39 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:39 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:46:39 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:46:39 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'jsshell-linux-x86_64.zip'], 03:46:39 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:46:39 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:46:39 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:46:39 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:46:39 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:46:39 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:46:39 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:46:39 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:46:39 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:46:39 INFO - https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:46:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:46:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:46:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:46:39 INFO - retry: Failed, sleeping 30 seconds before retrying 03:47:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:47:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:47:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:47:09 INFO - retry: Failed, sleeping 60 seconds before retrying 03:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:48:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:09 INFO - Caught exception: HTTP Error 404: Not Found 03:48:09 INFO - Caught exception: HTTP Error 404: Not Found 03:48:09 INFO - Caught exception: HTTP Error 404: Not Found 03:48:09 INFO - trying https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:10 INFO - Downloaded 21767300 bytes. 03:48:10 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:48:10 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:48:11 INFO - Return code: 0 03:48:11 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:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:48:11 INFO - https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:48:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:48:11 INFO - retry: Failed, sleeping 30 seconds before retrying 03:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:48:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:48:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:48:42 INFO - retry: Failed, sleeping 60 seconds before retrying 03:49:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:49:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:49:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:49:42 INFO - Caught exception: HTTP Error 404: Not Found 03:49:42 INFO - Caught exception: HTTP Error 404: Not Found 03:49:42 INFO - Caught exception: HTTP Error 404: Not Found 03:49:42 INFO - trying https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:49:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:49:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:49:42 INFO - Downloaded 21767300 bytes. 03:49:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:49:44 INFO - Return code: 0 03:49:44 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:49:44 INFO - https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:49:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:49:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:49:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:49:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:49:44 INFO - retry: Failed, sleeping 30 seconds before retrying 03:50:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:50:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:50:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:50:14 INFO - retry: Failed, sleeping 60 seconds before retrying 03:51:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:51:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:51:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:14 INFO - Caught exception: HTTP Error 404: Not Found 03:51:14 INFO - Caught exception: HTTP Error 404: Not Found 03:51:14 INFO - Caught exception: HTTP Error 404: Not Found 03:51:14 INFO - trying https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:51:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:15 INFO - Downloaded 14413723 bytes. 03:51:15 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:51:15 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:51:15 INFO - Return code: 0 03:51: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:51:15 INFO - https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:51:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:51:15 INFO - retry: Failed, sleeping 30 seconds before retrying 03:51:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:51:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:51:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:51:45 INFO - retry: Failed, sleeping 60 seconds before retrying 03:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:52:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:52:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:52:45 INFO - Caught exception: HTTP Error 404: Not Found 03:52:45 INFO - Caught exception: HTTP Error 404: Not Found 03:52:45 INFO - Caught exception: HTTP Error 404: Not Found 03:52:45 INFO - trying https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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:52:46 INFO - Downloaded 63731562 bytes. 03:52:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:46 INFO - mkdir: /builds/slave/test/properties 03:52:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:52:46 INFO - Writing to file /builds/slave/test/properties/build_url 03:52:46 INFO - Contents: 03:52:46 INFO - build_url:https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:52:47 INFO - Running post-action listener: _resource_record_post_action 03:52:47 INFO - Running post-action listener: set_extra_try_arguments 03:52:47 INFO - [mozharness: 2016-08-18 10:52:47.285917Z] Finished download-and-extract step (success) 03:52:47 INFO - [mozharness: 2016-08-18 10:52:47.286151Z] Running populate-webroot step. 03:52:47 INFO - Running pre-action listener: _resource_record_pre_action 03:52:47 INFO - Running main action method: populate_webroot 03:52:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:52:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:52:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:52:47 INFO - '--tppagecycles', 03:52:47 INFO - '7'], 03:52:47 INFO - 'tests': ['tsvgm']}}, 03:52:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:52:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:52:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:52:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:52:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:52:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['damp', 'tps']}, 03:52:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'tests': ['damp', 'tps']}, 03:52:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['dromaeo_dom']}, 03:52:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:52:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['basic_compositor_video']}, 03:52:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:52:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other-e10s': {'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['a11yr', 03:52:47 INFO - 'ts_paint', 03:52:47 INFO - 'tpaint', 03:52:47 INFO - 'sessionrestore', 03:52:47 INFO - 'sessionrestore_no_auto_restore', 03:52:47 INFO - 'tabpaint']}, 03:52:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tsvgx', 03:52:47 INFO - 'tsvgr_opacity', 03:52:47 INFO - 'tart', 03:52:47 INFO - 'tscrollx', 03:52:47 INFO - 'cart']}, 03:52:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:52:47 INFO - 'tsvgr_opacity', 03:52:47 INFO - 'tart', 03:52:47 INFO - 'tscrollx', 03:52:47 INFO - 'cart']}, 03:52:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s'], 03:52:47 INFO - 'tests': ['tp5o']}, 03:52:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'tests': ['tp5o']}, 03:52:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'talos_options': ['--disable-e10s', 03:52:47 INFO - '--xperf_path', 03:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:47 INFO - 'tests': ['tp5n']}, 03:52:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:52:47 INFO - 'talos_options': ['--xperf_path', 03:52:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:52:47 INFO - 'tests': ['tp5n']}}, 03:52:47 INFO - 'talos.zip': {'path': '', 03:52:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:52:47 INFO - Running post-action listener: _resource_record_post_action 03:52:47 INFO - [mozharness: 2016-08-18 10:52:47.308627Z] Finished populate-webroot step (success) 03:52:47 INFO - [mozharness: 2016-08-18 10:52:47.308716Z] Running create-virtualenv step. 03:52:47 INFO - Running pre-action listener: _resource_record_pre_action 03:52:47 INFO - Running main action method: create_virtualenv 03:52:47 INFO - Creating virtualenv /builds/slave/test/build/venv 03:52:47 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:52:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:52:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:52:47 INFO - Using real prefix '/usr' 03:52:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:52:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:52:49 INFO - Installing pip.................done. 03:52:49 INFO - Return code: 0 03:52:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:49 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:52:49 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:52:49 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:52:49 INFO - Using env: {'DISPLAY': ':0', 03:52:49 INFO - 'HOME': '/home/cltbld', 03:52:49 INFO - 'LANG': 'en_US.UTF-8', 03:52:49 INFO - 'LANGUAGE': 'en_US:en', 03:52:49 INFO - 'LOGNAME': 'cltbld', 03:52:49 INFO - 'MAIL': '/var/mail/cltbld', 03:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:49 INFO - 'MOZ_NO_REMOTE': '1', 03:52:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:49 INFO - 'NO_EM_RESTART': '1', 03:52:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:49 INFO - 'PWD': '/builds/slave/test', 03:52:49 INFO - 'SHELL': '/bin/bash', 03:52:49 INFO - 'SHLVL': '1', 03:52:49 INFO - 'TERM': 'linux', 03:52:49 INFO - 'TMOUT': '86400', 03:52:49 INFO - 'USER': 'cltbld', 03:52:49 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:49 INFO - '_': '/tools/buildbot/bin/python'} 03:52:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:50 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:52:50 INFO - Cleaning up... 03:52:50 INFO - Return code: 0 03:52:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:52:50 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:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:50 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:52:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:50 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:52:50 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:52:50 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:52:50 INFO - Using env: {'DISPLAY': ':0', 03:52:50 INFO - 'HOME': '/home/cltbld', 03:52:50 INFO - 'LANG': 'en_US.UTF-8', 03:52:50 INFO - 'LANGUAGE': 'en_US:en', 03:52:50 INFO - 'LOGNAME': 'cltbld', 03:52:50 INFO - 'MAIL': '/var/mail/cltbld', 03:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:50 INFO - 'MOZ_NO_REMOTE': '1', 03:52:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:50 INFO - 'NO_EM_RESTART': '1', 03:52:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:50 INFO - 'PWD': '/builds/slave/test', 03:52:50 INFO - 'SHELL': '/bin/bash', 03:52:50 INFO - 'SHLVL': '1', 03:52:50 INFO - 'TERM': 'linux', 03:52:50 INFO - 'TMOUT': '86400', 03:52:50 INFO - 'USER': 'cltbld', 03:52:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:50 INFO - '_': '/tools/buildbot/bin/python'} 03:52:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:50 INFO - Downloading/unpacking psutil>=3.1.1 03:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:52:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:52 INFO - Installing collected packages: psutil 03:52:52 INFO - Running setup.py install for psutil 03:52:52 INFO - building 'psutil._psutil_linux' extension 03:52:52 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:52:52 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:52:52 INFO - building 'psutil._psutil_posix' extension 03:52:52 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:52:52 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:52:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:52:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:52:52 INFO - Successfully installed psutil 03:52:52 INFO - Cleaning up... 03:52:52 INFO - Return code: 0 03:52:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:52 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:52:52 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:52:52 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:52:52 INFO - Using env: {'DISPLAY': ':0', 03:52:52 INFO - 'HOME': '/home/cltbld', 03:52:52 INFO - 'LANG': 'en_US.UTF-8', 03:52:52 INFO - 'LANGUAGE': 'en_US:en', 03:52:52 INFO - 'LOGNAME': 'cltbld', 03:52:52 INFO - 'MAIL': '/var/mail/cltbld', 03:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:52 INFO - 'MOZ_NO_REMOTE': '1', 03:52:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:52 INFO - 'NO_EM_RESTART': '1', 03:52:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:52 INFO - 'PWD': '/builds/slave/test', 03:52:52 INFO - 'SHELL': '/bin/bash', 03:52:52 INFO - 'SHLVL': '1', 03:52:52 INFO - 'TERM': 'linux', 03:52:52 INFO - 'TMOUT': '86400', 03:52:52 INFO - 'USER': 'cltbld', 03:52:52 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:52 INFO - '_': '/tools/buildbot/bin/python'} 03:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:52:53 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:52:53 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:52:53 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:52:53 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:52:53 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:52:53 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:52:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:52:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:52:55 INFO - Installing collected packages: mozsystemmonitor 03:52:55 INFO - Running setup.py install for mozsystemmonitor 03:52:55 INFO - Successfully installed mozsystemmonitor 03:52:55 INFO - Cleaning up... 03:52:55 INFO - Return code: 0 03:52:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:55 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:52:55 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:52:55 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:52:55 INFO - Using env: {'DISPLAY': ':0', 03:52:55 INFO - 'HOME': '/home/cltbld', 03:52:55 INFO - 'LANG': 'en_US.UTF-8', 03:52:55 INFO - 'LANGUAGE': 'en_US:en', 03:52:55 INFO - 'LOGNAME': 'cltbld', 03:52:55 INFO - 'MAIL': '/var/mail/cltbld', 03:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:55 INFO - 'MOZ_NO_REMOTE': '1', 03:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:55 INFO - 'NO_EM_RESTART': '1', 03:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:55 INFO - 'PWD': '/builds/slave/test', 03:52:55 INFO - 'SHELL': '/bin/bash', 03:52:55 INFO - 'SHLVL': '1', 03:52:55 INFO - 'TERM': 'linux', 03:52:55 INFO - 'TMOUT': '86400', 03:52:55 INFO - 'USER': 'cltbld', 03:52:55 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:55 INFO - '_': '/tools/buildbot/bin/python'} 03:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:52:55 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:52:55 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:52:55 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:52:55 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:52:55 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:52:55 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:52:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:52:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:52:57 INFO - Downloading functools32-3.2.3-2.tar.gz 03:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:52:57 INFO - warning: no files found matching '*.txt' 03:52:57 INFO - no previously-included directories found matching 'build' 03:52:57 INFO - no previously-included directories found matching 'dist' 03:52:57 INFO - no previously-included directories found matching '.git*' 03:52:57 INFO - Installing collected packages: jsonschema, functools32 03:52:57 INFO - Running setup.py install for functools32 03:52:57 INFO - warning: no files found matching '*.txt' 03:52:57 INFO - no previously-included directories found matching 'build' 03:52:57 INFO - no previously-included directories found matching 'dist' 03:52:57 INFO - no previously-included directories found matching '.git*' 03:52:57 INFO - Successfully installed jsonschema functools32 03:52:57 INFO - Cleaning up... 03:52:57 INFO - Return code: 0 03:52:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:52:57 INFO - Using env: {'DISPLAY': ':0', 03:52:57 INFO - 'HOME': '/home/cltbld', 03:52:57 INFO - 'LANG': 'en_US.UTF-8', 03:52:57 INFO - 'LANGUAGE': 'en_US:en', 03:52:57 INFO - 'LOGNAME': 'cltbld', 03:52:57 INFO - 'MAIL': '/var/mail/cltbld', 03:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:57 INFO - 'MOZ_NO_REMOTE': '1', 03:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:57 INFO - 'NO_EM_RESTART': '1', 03:52:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:57 INFO - 'PWD': '/builds/slave/test', 03:52:57 INFO - 'SHELL': '/bin/bash', 03:52:57 INFO - 'SHLVL': '1', 03:52:57 INFO - 'TERM': 'linux', 03:52:57 INFO - 'TMOUT': '86400', 03:52:57 INFO - 'USER': 'cltbld', 03:52:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:57 INFO - '_': '/tools/buildbot/bin/python'} 03:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:52:58 INFO - Cleaning up... 03:52:58 INFO - Return code: 0 03:52:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:52:58 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:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:58 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:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:52:58 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:52:58 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:52:58 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:52:58 INFO - Using env: {'DISPLAY': ':0', 03:52:58 INFO - 'HOME': '/home/cltbld', 03:52:58 INFO - 'LANG': 'en_US.UTF-8', 03:52:58 INFO - 'LANGUAGE': 'en_US:en', 03:52:58 INFO - 'LOGNAME': 'cltbld', 03:52:58 INFO - 'MAIL': '/var/mail/cltbld', 03:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:58 INFO - 'MOZ_NO_REMOTE': '1', 03:52:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:52:58 INFO - 'NO_EM_RESTART': '1', 03:52:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:52:58 INFO - 'PWD': '/builds/slave/test', 03:52:58 INFO - 'SHELL': '/bin/bash', 03:52:58 INFO - 'SHLVL': '1', 03:52:58 INFO - 'TERM': 'linux', 03:52:58 INFO - 'TMOUT': '86400', 03:52:58 INFO - 'USER': 'cltbld', 03:52:58 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:52:58 INFO - '_': '/tools/buildbot/bin/python'} 03:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:52:58 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:52:58 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:52:58 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:52:58 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:52:58 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:52:58 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:53:00 INFO - Downloading blobuploader-1.2.4.tar.gz 03:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:53:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:53:00 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:53:00 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:53:00 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:53:00 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:53:00 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:53:00 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:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:53:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:53:00 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:53:00 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:53:00 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:53:00 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:53:00 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:53:00 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:53:00 INFO - Downloading docopt-0.6.1.tar.gz 03:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:53:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:53:01 INFO - Running setup.py install for blobuploader 03:53:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:53:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:53:01 INFO - Running setup.py install for requests 03:53:01 INFO - Running setup.py install for docopt 03:53:01 INFO - Successfully installed blobuploader requests docopt 03:53:01 INFO - Cleaning up... 03:53:01 INFO - Return code: 0 03:53:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:01 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:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:01 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:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:01 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:53:01 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:53:01 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:53:01 INFO - Using env: {'DISPLAY': ':0', 03:53:01 INFO - 'HOME': '/home/cltbld', 03:53:01 INFO - 'LANG': 'en_US.UTF-8', 03:53:01 INFO - 'LANGUAGE': 'en_US:en', 03:53:01 INFO - 'LOGNAME': 'cltbld', 03:53:01 INFO - 'MAIL': '/var/mail/cltbld', 03:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:01 INFO - 'MOZ_NO_REMOTE': '1', 03:53:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:01 INFO - 'NO_EM_RESTART': '1', 03:53:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:01 INFO - 'PWD': '/builds/slave/test', 03:53:01 INFO - 'SHELL': '/bin/bash', 03:53:01 INFO - 'SHLVL': '1', 03:53:01 INFO - 'TERM': 'linux', 03:53:01 INFO - 'TMOUT': '86400', 03:53:01 INFO - 'USER': 'cltbld', 03:53:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:01 INFO - '_': '/tools/buildbot/bin/python'} 03:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:01 INFO - Running setup.py (path:/tmp/pip-d0sgIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:01 INFO - Running setup.py (path:/tmp/pip-2k5GQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:01 INFO - Running setup.py (path:/tmp/pip-CEZLdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:02 INFO - Running setup.py (path:/tmp/pip-5dHAtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:02 INFO - Running setup.py (path:/tmp/pip-IoHGCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:02 INFO - Running setup.py (path:/tmp/pip-Mvf08W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:02 INFO - Running setup.py (path:/tmp/pip-8S_Ggp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:02 INFO - Running setup.py (path:/tmp/pip-jo3HQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:02 INFO - Running setup.py (path:/tmp/pip-hxnB5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:02 INFO - Running setup.py (path:/tmp/pip-LQU8h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:02 INFO - Running setup.py (path:/tmp/pip-YBib0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:02 INFO - Running setup.py (path:/tmp/pip-R1aVSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:02 INFO - Running setup.py (path:/tmp/pip-HtRhlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:02 INFO - Running setup.py (path:/tmp/pip-AfL8Fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:02 INFO - Running setup.py (path:/tmp/pip-Qyjdqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:02 INFO - Running setup.py (path:/tmp/pip-EaUkIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:03 INFO - Running setup.py (path:/tmp/pip-v9EdQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:53:03 INFO - Running setup.py install for manifestparser 03:53:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Running setup.py install for mozcrash 03:53:03 INFO - Running setup.py install for mozdebug 03:53:03 INFO - Running setup.py install for mozdevice 03:53:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Running setup.py install for mozfile 03:53:03 INFO - Running setup.py install for mozhttpd 03:53:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Running setup.py install for mozinfo 03:53:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Running setup.py install for mozInstall 03:53:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:53:03 INFO - Running setup.py install for mozleak 03:53:03 INFO - Running setup.py install for mozlog 03:53:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Running setup.py install for moznetwork 03:53:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Running setup.py install for mozprocess 03:53:04 INFO - Running setup.py install for mozprofile 03:53:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Running setup.py install for mozrunner 03:53:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Running setup.py install for mozscreenshot 03:53:04 INFO - Running setup.py install for moztest 03:53:04 INFO - Running setup.py install for mozversion 03:53:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:53:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:53:04 INFO - Cleaning up... 03:53:04 INFO - Return code: 0 03:53:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:04 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:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:04 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:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:04 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:53:04 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:53:04 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:53:04 INFO - Using env: {'DISPLAY': ':0', 03:53:04 INFO - 'HOME': '/home/cltbld', 03:53:04 INFO - 'LANG': 'en_US.UTF-8', 03:53:04 INFO - 'LANGUAGE': 'en_US:en', 03:53:04 INFO - 'LOGNAME': 'cltbld', 03:53:04 INFO - 'MAIL': '/var/mail/cltbld', 03:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:04 INFO - 'MOZ_NO_REMOTE': '1', 03:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:04 INFO - 'NO_EM_RESTART': '1', 03:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:04 INFO - 'PWD': '/builds/slave/test', 03:53:04 INFO - 'SHELL': '/bin/bash', 03:53:04 INFO - 'SHLVL': '1', 03:53:04 INFO - 'TERM': 'linux', 03:53:04 INFO - 'TMOUT': '86400', 03:53:04 INFO - 'USER': 'cltbld', 03:53:04 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:04 INFO - '_': '/tools/buildbot/bin/python'} 03:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:53:05 INFO - Running setup.py (path:/tmp/pip-VUNZqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:53:05 INFO - Running setup.py (path:/tmp/pip-qCehrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:53:05 INFO - Running setup.py (path:/tmp/pip-S_9M_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:53:05 INFO - Running setup.py (path:/tmp/pip-k9skMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:53:05 INFO - Running setup.py (path:/tmp/pip-QH9UGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:53:05 INFO - Running setup.py (path:/tmp/pip-iLzbBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:53:05 INFO - Running setup.py (path:/tmp/pip-fseYmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:53:05 INFO - Running setup.py (path:/tmp/pip-J2uGSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:53:05 INFO - Running setup.py (path:/tmp/pip-k56HUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:53:05 INFO - Running setup.py (path:/tmp/pip-uUMiAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:53:05 INFO - Running setup.py (path:/tmp/pip-cisk4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:53:05 INFO - Running setup.py (path:/tmp/pip-rBI5EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:53:05 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:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:53:05 INFO - Running setup.py (path:/tmp/pip-zasG6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:53:06 INFO - Running setup.py (path:/tmp/pip-6JVsSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:06 INFO - Running setup.py (path:/tmp/pip-1mbZvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:53:06 INFO - Running setup.py (path:/tmp/pip-eOt0jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:53:06 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:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:53:06 INFO - Running setup.py (path:/tmp/pip-3hSExT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:53:06 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:53:06 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:53:06 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:53:06 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:53:06 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:53:06 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:53:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:53:06 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:53:06 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:53:06 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:53:06 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:53:06 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:53:06 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:53:09 INFO - Downloading blessings-1.6.tar.gz 03:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:53:09 INFO - Installing collected packages: blessings 03:53:09 INFO - Running setup.py install for blessings 03:53:09 INFO - Successfully installed blessings 03:53:09 INFO - Cleaning up... 03:53:09 INFO - Return code: 0 03:53:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:09 INFO - Reading from file tmpfile_stdout 03:53:09 INFO - Current package versions: 03:53:09 INFO - argparse == 1.2.1 03:53:09 INFO - blessings == 1.6 03:53:09 INFO - blobuploader == 1.2.4 03:53:09 INFO - docopt == 0.6.1 03:53:09 INFO - functools32 == 3.2.3-2 03:53:09 INFO - jsonschema == 2.5.1 03:53:09 INFO - manifestparser == 1.1 03:53:09 INFO - mozInstall == 1.12 03:53:09 INFO - mozcrash == 0.17 03:53:09 INFO - mozdebug == 0.1 03:53:09 INFO - mozdevice == 0.48 03:53:09 INFO - mozfile == 1.2 03:53:09 INFO - mozhttpd == 0.7 03:53:09 INFO - mozinfo == 0.9 03:53:09 INFO - mozleak == 0.1 03:53:09 INFO - mozlog == 3.3 03:53:09 INFO - moznetwork == 0.27 03:53:09 INFO - mozprocess == 0.23 03:53:09 INFO - mozprofile == 0.28 03:53:09 INFO - mozrunner == 6.12 03:53:09 INFO - mozscreenshot == 0.1 03:53:09 INFO - mozsystemmonitor == 0.3 03:53:09 INFO - moztest == 0.7 03:53:09 INFO - mozversion == 1.4 03:53:09 INFO - psutil == 3.1.1 03:53:09 INFO - requests == 1.2.3 03:53:09 INFO - wsgiref == 0.1.2 03:53:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:09 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:53:09 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:53:09 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:53:09 INFO - Using env: {'DISPLAY': ':0', 03:53:09 INFO - 'HOME': '/home/cltbld', 03:53:09 INFO - 'LANG': 'en_US.UTF-8', 03:53:09 INFO - 'LANGUAGE': 'en_US:en', 03:53:09 INFO - 'LOGNAME': 'cltbld', 03:53:09 INFO - 'MAIL': '/var/mail/cltbld', 03:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:09 INFO - 'MOZ_NO_REMOTE': '1', 03:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:09 INFO - 'NO_EM_RESTART': '1', 03:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:09 INFO - 'PWD': '/builds/slave/test', 03:53:09 INFO - 'SHELL': '/bin/bash', 03:53:09 INFO - 'SHLVL': '1', 03:53:09 INFO - 'TERM': 'linux', 03:53:09 INFO - 'TMOUT': '86400', 03:53:09 INFO - 'USER': 'cltbld', 03:53:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:09 INFO - '_': '/tools/buildbot/bin/python'} 03:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 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:53:09 INFO - Cleaning up... 03:53:09 INFO - Return code: 0 03:53:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:53:09 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 0x139a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x165b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, '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-1471517106.477723-791055313', '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:53:09 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:53:09 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:53:09 INFO - Using env: {'DISPLAY': ':0', 03:53:09 INFO - 'HOME': '/home/cltbld', 03:53:09 INFO - 'LANG': 'en_US.UTF-8', 03:53:09 INFO - 'LANGUAGE': 'en_US:en', 03:53:09 INFO - 'LOGNAME': 'cltbld', 03:53:09 INFO - 'MAIL': '/var/mail/cltbld', 03:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:09 INFO - 'MOZ_NO_REMOTE': '1', 03:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:09 INFO - 'NO_EM_RESTART': '1', 03:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:09 INFO - 'PWD': '/builds/slave/test', 03:53:09 INFO - 'SHELL': '/bin/bash', 03:53:09 INFO - 'SHLVL': '1', 03:53:09 INFO - 'TERM': 'linux', 03:53:09 INFO - 'TMOUT': '86400', 03:53:09 INFO - 'USER': 'cltbld', 03:53:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:09 INFO - '_': '/tools/buildbot/bin/python'} 03:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:53:09 INFO - Cleaning up... 03:53:09 INFO - Return code: 0 03:53:09 INFO - Running post-action listener: _resource_record_post_action 03:53:09 INFO - Running post-action listener: _start_resource_monitoring 03:53:09 INFO - Starting resource monitoring. 03:53:09 INFO - [mozharness: 2016-08-18 10:53:09.995269Z] Finished create-virtualenv step (success) 03:53:09 INFO - [mozharness: 2016-08-18 10:53:09.995743Z] Running install step. 03:53:09 INFO - Running pre-action listener: _resource_record_pre_action 03:53:09 INFO - Running main action method: install 03:53:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:53:10 INFO - Reading from file tmpfile_stdout 03:53:10 INFO - Detecting whether we're running mozinstall >=1.0... 03:53:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:53:10 INFO - Reading from file tmpfile_stdout 03:53:10 INFO - Output received: 03:53:10 INFO - Usage: mozinstall [options] installer 03:53:10 INFO - Options: 03:53:10 INFO - -h, --help show this help message and exit 03:53:10 INFO - -d DEST, --destination=DEST 03:53:10 INFO - Directory to install application into. [default: 03:53:10 INFO - "/builds/slave/test"] 03:53:10 INFO - --app=APP Application being installed. [default: firefox] 03:53:10 INFO - mkdir: /builds/slave/test/build/application 03:53:10 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:53:10 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:53:26 INFO - Reading from file tmpfile_stdout 03:53:26 INFO - Output received: 03:53:26 INFO - /builds/slave/test/build/application/firefox/firefox 03:53:26 INFO - Running post-action listener: _resource_record_post_action 03:53:26 INFO - [mozharness: 2016-08-18 10:53:26.831280Z] Finished install step (success) 03:53:26 INFO - [mozharness: 2016-08-18 10:53:26.831558Z] Running run-tests step. 03:53:26 INFO - Running pre-action listener: _resource_record_pre_action 03:53:26 INFO - Running main action method: run_tests 03:53:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:53:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:53:26 INFO - Python 2.7.3 03:53:26 INFO - Return code: 0 03:53:26 INFO - grabbing minidump binary from tooltool 03:53:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:53:26 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 0x16593b0>, '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 0x165a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x165a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:53:26 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:53:26 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:53:26 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:53:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:53:26 INFO - Return code: 0 03:53:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:53:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:53:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:53:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:53:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:53:26 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/Lrz76RxxQ-CJWYAYHohH2A/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:53:26 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/Lrz76RxxQ-CJWYAYHohH2A/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:53:26 INFO - Using env: {'DISPLAY': ':0', 03:53:26 INFO - 'HOME': '/home/cltbld', 03:53:26 INFO - 'LANG': 'en_US.UTF-8', 03:53:26 INFO - 'LANGUAGE': 'en_US:en', 03:53:26 INFO - 'LOGNAME': 'cltbld', 03:53:26 INFO - 'MAIL': '/var/mail/cltbld', 03:53:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:26 INFO - 'MOZ_NO_REMOTE': '1', 03:53:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:26 INFO - 'NO_EM_RESTART': '1', 03:53:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:26 INFO - 'PWD': '/builds/slave/test', 03:53:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:26 INFO - 'SHELL': '/bin/bash', 03:53:26 INFO - 'SHLVL': '1', 03:53:26 INFO - 'TERM': 'linux', 03:53:26 INFO - 'TMOUT': '86400', 03:53:26 INFO - 'USER': 'cltbld', 03:53:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1471517106.477723-791055313', 03:53:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:26 INFO - '_': '/tools/buildbot/bin/python'} 03:53:26 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/Lrz76RxxQ-CJWYAYHohH2A/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:53:27 INFO - mozversion application_buildid: 20160818004001 03:53:27 INFO - mozversion application_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 03:53:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:53:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:53:27 INFO - mozversion application_name: Firefox 03:53:27 INFO - mozversion application_remotingname: firefox-dev 03:53:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:27 INFO - mozversion application_vendor: Mozilla 03:53:27 INFO - mozversion application_version: 50.0a2 03:53:27 INFO - mozversion platform_buildid: 20160818004001 03:53:27 INFO - mozversion platform_changeset: d23f68f5cb77934e673700bfaaaf607893a2df9b 03:53:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:53:27 INFO - mozversion platform_version: 50.0a2 03:53:27 INFO - using testdate: 1471517607 03:53:27 INFO - actual date: 1471517607 03:53:27 INFO - starting webserver on 'localhost:35418' 03:53:27 INFO - SUITE-START | Running 2 tests 03:53:27 INFO - TEST-START | dromaeo_css 03:53:27 INFO - operating with platform_type : linux_ 03:53:27 INFO - Initialising browser for dromaeo_css test... 03:53:27 INFO - TEST-INFO | started process 27960 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaAZw95/profile http://localhost:35418/getInfo.html) 03:53:31 INFO - PROCESS | 27960 | __metrics Screen width/height:1600/1200 03:53:31 INFO - PROCESS | 27960 | colorDepth:24 03:53:31 INFO - PROCESS | 27960 | Browser inner width/height: 1024/697 03:53:31 INFO - PROCESS | 27960 | __metrics 03:53:31 INFO - PROCESS | 27960 | 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:53:31 INFO - PROCESS | 27960 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:53:32 INFO - TEST-INFO | 27960: exit 0 03:53:32 INFO - Browser initialized. 03:53:32 INFO - Running cycle 1/1 for dromaeo_css test... 03:53:32 INFO - TEST-INFO | started process 28048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaAZw95/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:44 INFO - PROCESS | 28048 | RSS: Main: 145420288 03:53:44 INFO - PROCESS | 28048 | 03:54:35 INFO - PROCESS | 28048 | 1471517675416 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:56:44 INFO - PROCESS | 28048 | Cycle 1(1): loaded http://localhost:35418/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35418/tests/dromaeo/cssquery-ext.html) 03:56:45 INFO - PROCESS | 28048 | RSS: Main: 223420416 03:56:45 INFO - PROCESS | 28048 | 03:59:47 INFO - PROCESS | 28048 | Cycle 1(1): loaded http://localhost:35418/tests/dromaeo/cssquery-ext.html (next: http://localhost:35418/tests/dromaeo/cssquery-jquery.html) 03:59:48 INFO - PROCESS | 28048 | RSS: Main: 236720128 03:59:48 INFO - PROCESS | 28048 | 04:02:50 INFO - PROCESS | 28048 | Cycle 1(1): loaded http://localhost:35418/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35418/tests/dromaeo/cssquery-mootools.html) 04:02:50 INFO - PROCESS | 28048 | RSS: Main: 175185920 04:02:50 INFO - PROCESS | 28048 | 04:05:52 INFO - PROCESS | 28048 | Cycle 1(1): loaded http://localhost:35418/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35418/tests/dromaeo/cssquery-prototype.html) 04:05:53 INFO - PROCESS | 28048 | RSS: Main: 220409856 04:05:53 INFO - PROCESS | 28048 | 04:08:55 INFO - PROCESS | 28048 | Cycle 1(1): loaded http://localhost:35418/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35418/tests/dromaeo/cssquery-yui.html) 04:08:56 INFO - PROCESS | 28048 | RSS: Main: 179200000 04:08:56 INFO - PROCESS | 28048 | 04:11:58 INFO - PROCESS | 28048 | Cycle 1(1): loaded http://localhost:35418/tests/dromaeo/cssquery-yui.html (next: http://localhost:35418/tests/dromaeo/cssquery-dojo.html) 04:11:59 INFO - PROCESS | 28048 | RSS: Main: 268120064 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | __start_tp_report 04:11:59 INFO - PROCESS | 28048 | _x_x_mozilla_page_load 04:11:59 INFO - PROCESS | 28048 | _x_x_mozilla_page_load_details 04:11:59 INFO - PROCESS | 28048 | |i|pagename|runs| 04:11:59 INFO - PROCESS | 28048 | |0;dojo.html;3251.3172233830896;3256.251062255681;3272.967270327296;3307.8676852925882;3318.917027074323;5546.77812887484;5635.3237611486575;5645.971770141148;5664.2636457260605;5678.659280443171;5637.577181711367;5641.351244606872;5648.774049038044;5654.971725141369;5701.600887937846;4944.9258261126115;4964.677295975768;5069.771860266297;5074.61940354474;5119.462456442076;4991;5073.4926507349155;5090.669106508084;5091.745412729368;5095.082885080684;4976.278439626252;5001.349824522807;5031.597472202233;5038.5969122470115;5067.163917953533;5138.332016837825;5157.742112894341;5166.819161329373;5167.586593072564;5180.896382072357;4947.851564453073;4958.380202474691;4977.253411988195;5054.21659642756;5081.872953176163;5562.888742225133;5567.582431317627;5579.8605034874045;5600;5604.775808967677;6064.854867098253;6073.237863496781;6084.26988761347;6088.756449742005;6093.6039157454625;5984.311804142492;6003;6028.788992385245;6094.058517321852;6101.572889897709;5633.718314084321;5664.773409063632;5672.517835983905;5686.09022556389;5687.402822703557;5929.911051334234;6183.690815459209;6201;6204.875902481925;6209.316975132731;5837.036888913367;5864.648121112747;5881.558883083786;5948.672822994777;5972.074328479092;6019.067044608093;6048.6673232971725;6051.394860514001;6062;6071.787487438007;5696.3733989261145;5973.133895585116;5984;5994.700264986734;6004.279486461653;5908.970455147697;5949.881002379928;5959.493443057302;6018.187544681499;6032.095185722089;4594.081183763261;4600.7929643166135;4601.930971035437;4603.861884143481;4630.629549636021;4044.211378781168;4112.979435102805;4129.607687269705;4167.6874234432535;4172.70791044624;5675.460831221028;5703.17303990919;5705;5761.222234998306;5782.508486778671;6109.114178444101;6109.266887973472;6112.541559383064;6114.685203258513;6114.877702445926;5549.278593782863;5738.397468265772;5763.94236057634;5764;5765.971170144123;5011.824586139468;5060.7216603086445;5060.797568097235;5084.6440749147205;5105.591552675852;6252.624842509464;6263.655498947602;6263.749450022039;6264.342244064308;6271.090691849657;5825.126231065374;6039.4262545058145;6068.332483426908;6081.787137450168;6089.238845144357;6130.141780150871;6164.969175154275;6175.505959523138;6218.409251121137;6252.187215661935;5864.824055278348;5935.436133567304;5937.495312898279;5941.940580594139;5989.940100598938;5829;6048.637081775108;6060.454559089703;6076.33160352352;6079.300880398634;5857.414258574108;5891.793787217427;5910.970445147746;5963.642181469126;5994.520438364833;5865.354810970704;5870.442307980735;5871.94128058714;5877.059670452707;5894.498967587803;5637.746301416364;5814.098814683731;5829.213056237354;5841.678707671119;5842.503387211965;5760.251167348218;5798.10129429939;5829.038208695687;5856.502197313276;5898.587098903035;5703.714814259353;5710.942890571041;5713.600047996601;5714.657120572779;5716.657000579979 04:11:59 INFO - PROCESS | 28048 | |1;ext.html;594.7620951619351;618.1222663817379;642.5149012495565;663.5620490476283;673.7675501951825;34675.306493870106;38168.427473587886;38345.424818627675;38372.23255534891;46571.76714116433;44173.337399939024;49371;49501.75249123758;49537.75231123839;49574.50425495744;9382.530873456317;9541.141297283242;9559.904400956006;9561.80876382472;9577.138057574817;8675.219230269275;8889.333300002494;9308.58111384986;9401.153896149344;9408.576614052385;11233.21367504275;11486.195966282363;11506.482208300604;11965.641030769091;11968.82046769299;11419.257748246337;11453.312801231952;11454.02640775531;11493.252938559008;11588.246763960316;11093.611723589636;11325.773484530264;11329.15031372642;11332.093432525378;11339.489722962486;46274.768626156656;46666.53333466656;46669.29996050062;46738.298925516145;46759.29861052087;4024.5774193709826;4119.114390406077;4207.852725154636;4421.823127074913;4699.530046995307;35476.82261588702;36071.72248684485;36322.36095346184;36643.63356366455;37103.62896371056;4791.4250289965075;4855.295982082614;4938.407391113089;4949.282354058641;5018;384314;384559.077204615;385291.9999999972;385572;385752;65907.34092659107;66268.33731662622;66290.33709662939;66293.33706662968;66323.66838165875;40485.79757101196;41210.587894120676;41342;43266.13467730691;69637.3036269641;17965.640687186184;18141.45575632733;18316.084195790158;18326.083695815156;18339.54151146232;12981.545645902348;13308.800367994489;13329.600111996655;13397.598072057854;13413.731725365438;18936.90531547361;20499.590008199753;21190.258340957993;21283.255086072222;21474.677879831503;7469.402447804163;7547.773566793005;7692.730754423568;7785.766427007199;7875.684972601146;1064.5156453813497;1110.7500812317164;1159.171192597297;1216.8904798568171;1225.1424003197797;34068.65931340704;34224.82887585596;34433.13917152091;34468;34661.13347166341;1124.6963319903743;1166.416791604198;1224.834647322618;1233.8827811357908;1234.919730217533;18220.180091895898;18434.81565184358;18449.53876153107;18613.7207941881;18632.25470981173;586576;596002.0599691009;596256;597051;598433;64762.35237647658;65304.67347663231;65455.34544654588;65488.67255663787;65532;1354.3025341948903;1447.117258472331;1468.6401831551339;1492.8731057860205;1508.547435769265;35805.283894322485;36916;37189.25621487609;37680;37811.81094094622;56789.43210568007;57296.42703572911;57303.713481433995;57438.7128064357;57485.42514574968;351077;351134;351205;352040;352877;31043.37913241768;31779.20551986219;31967.840160799977;32032.039038828872;33561.83219083889;53367.7331613355;54294.72852635712;54866.451335486134;54893.45106549043;55612.443875560726;45899.77050114728;46587.53412465967;46680;47547.28679069748;48420.03159936711;45716.77141614271;46006.53993460022;46090.539094609965;46170.53829461662;47803.28295075646;45601;46361.76819115883;46472.070558589316;46521.76739116283;47089.76455117839;38394;39885.40171897482;40725.796371017954;40956.56652017165;41452;28440.530554427132;28884.855575721987;29002.709972900844;29200;29334.853325733235 04:11:59 INFO - PROCESS | 28048 | |2;jquery.html;392.1529496288018;412.4205491284745;413.1262380066161;418.89946412860905;434.9478062632485;14695.559133226006;19987.900060499713;20610.690839637417;20621.17515299388;20748.27381041664;19710.113044912978;19952;19986.20055197794;19991.800081999176;20176.394708158743;5572.247746554204;5593.720313984295;5847;5894.734736936826;5912.763489460416;5837.241158649391;5906.143609176666;5910.704464776765;5914.4381283777975;5915.940840591582;5589.832305030835;5600.663960162383;5629.577781666371;5633.239512665799;5637.182608521783;5604.103343465026;5655.519280861132;5679.772809087653;5696.430356964312;5703.54371650267;5270.578353731692;5297.152455607084;5309.309789727349;5311;5313.946860531384;23048.42378940523;23703.288901332988;23749.406264843343;23881;23955.281341559607;2279.464325883416;2350.21267875262;2445.3886528367907;2636.6308716779668;2695.7169497202904;9955.601775928953;10015.048570385801;10207;10207.744806379826;13376.729210725063;2010.0854111379288;2021.3026505855457;2070.8136267735977;2085.18677715691;2124.511362386644;87109.99999999936;87951;88624.11375886224;89491.10508894893;92045;9582.425054496753;9742.512874356396;9817.460039697742;10698.839517407245;10894;14636;14655.853441465662;14669.706605867823;14693.55919322422;14700;7920;8196.877046844302;8312.501249924902;8366.623501942428;8542.658293668308;8143.185681431928;8144.83710325802;8200.58997050145;8331.458455200393;8445.788855278544;4386.934195987063;4526.23054080807;4526.864194074117;4591.586757191869;4607.861764147081;1869.5793446474433;1876.7091100879386;1882.2376937340398;1917.1978340360295;1977;968.4189486308159;1014.573878970834;1057.7567159553346;1063.6436793674206;1083.6803143072812;18145;19523.51191220231;19598.8040119597;19699.50751231202;19952;990.7374545745383;993.2749093162032;994.9303548751662;1025;1064.9307794993301;10357.4303413313;11000.724981875517;11099.500522476508;11101.72245693848;11160.107191424666;540164;561155;561302;567176;574129;73328.53342933218;93866.53066734759;98141.5092924545;102706;105652.47173764235;1011.6206422591529;1019.2966852871494;1041.5937784264142;1049.895010498944;1051.279873286801;17822.643547128726;17964.281428742703;18104.18531166127;18219.90890045541;18650.72023919615;45386.546134538235;45391.319130213735;45730.771346143054;45943.77028114838;46408.53591464042;62928.37071629225;63765.681171593846;64097;64125.67937160284;65146;8613.48319100856;8622.525761083201;8820.250278726124;8832.779180520538;8838.823223535623;11607.709807254887;13735.931320343334;13776.724465510833;13934.512292069729;14035.649108772363;11997.220180688228;12144.271343719405;12213.816792748294;12379.93810030944;12543.623691289276;11978.281303121841;12025.518979240733;12195.390230488618;12358.196717213352;12490.375481226085;12168.391580421121;12259.877401225873;12335.3832308386;12414.503419863104;12432.937835310766;8368.372372072241;8598.140185981352;8614.65541378338;8831.470111793313;8840.469571825712;11909.166358355178;12005.939970300093;12137.635870923887;12159.635210943687;12189.268643881396 04:11:59 INFO - PROCESS | 28048 | |3;mootools.html;1556.3618916244343;1612.459825958304;1791.373019443195;1893.100777130862;1904.4001139641011;9152.908470915288;9923.106920377164;9926.85109723353;9934.40393576385;9941.304108712375;8391.664333426656;9103.681371152012;9386.29602779791;9401.012893646172;9679;2458.975410245897;2493.289412517433;2494.550980823451;2494.775470207681;2495.3512086857368;2486.577281862079;2562.7309132541095;2566.6278389633585;2567.26832852637;2568.268043607572;2528.51958127956;2532.962005569909;2561.129216066537;2568.024150822685;2569.9486010279784;2479.5412848623064;2481.5036992601463;2487.2786891801356;2504.3989442533843;2504.8246622736415;2470.7899828514596;2481.6773819403543;2485.652008718781;2487.2662564543502;2492.800575953919;9611.471369074698;9790.657326993593;9795.559199836025;9797.559109840004;9806.90193098067;5612.971935140339;5756.02147634903;5756.251687280668;5757.884842303172;5758.827235182951;3468.601110872257;3589.2642008388216;3646.106703857545;3671.1005803578028;3676.2279921216577;1107.3521989636072;1185.0637995983163;1193.576280420447;1198.4427241332821;1223.2966044524346;14043.368048437846;14073.577792666236;14078.788818167735;14083.577492675236;14126.858731412656;5038.5969122470115;5073.543381095719;5210.322658054504;5231.084560201949;5270.235815806686;11658.591949281905;11717.355545444912;11743.88256117445;11787.764244715057;11793.941030294794;5833.445822646841;5850.23946886902;5856.209411729362;5870.471657550841;5870.823875283749;4572.839950601713;4667.486576476585;5198.766055527511;5201.245819356172;5355.651882627617;4366.563343665672;4378.321360189176;4457.153140903218;4487.573680500413;4525.886852828706;1380.0822453068597;1394.3864699532173;1396.0995158122953;1401.5515035188641;1419.7160567886258;963.2871674960479;1003.5734812704571;1057.8677665291839;1060.9628662996759;1073.1790180511914;6397.648129352837;6653.800385988428;6730.629815360202;6784.559003664746;6934.965325173342;1019.9643012494527;1021.5403068619152;1025.7640742629087;1049.9265051446478;1089.2484185911696;4463.486699029653;4730.337752714623;4797.112534181118;4815.2054910939305;4916.827911023097;11984.58053968107;12207.694807629703;12348.320842353578;12357.196782209128;12407.44166512509;11444.313341199555;11948.581799636971;11986.88013119875;12148.817767733492;12151.756964860653;9038.141376569216;9167.495787731605;9244.075592440837;9251.07489251083;9258.95370523143;6561.174062796234;6864.965675171592;6873.25510923899;6877.50954245778;6922.930770692228;11057.889421106007;11100.722481938015;11142.888571114185;11222.326660400402;11275.943620281847;10114.848277275994;11212.215144939775;11384.14618903569;11407.429628518375;11529.423528823358;8242.17578242171;8453.281471075028;8545.444546104483;8711.91288087111;8724.869126962974;9640.469774162488;9670.951645241728;9736;9744.805103898025;9749.756256093655;8242.546659933761;8291.253787159185;8354.665813367397;8381.078081411162;8491.278241349555;8461.450005749606;8490.575471226537;8518.78703032429;8661.307095432225;8672.349573781865;7713.884291735741;7728.26581474759;7742.535447873146;7763.301302882767;7781.9610901945125;5819.330776960534;5851.61964472308;5919.082542205965;5960.940390596039;6049.153118563317;5326.493983071602;5430.294061771944;5487.835364939058;5489.945100549103;5666.376698563237 04:11:59 INFO - PROCESS | 28048 | |4;prototype.html;323.5324955439391;328.99013029609114;336.45158391821326;343.3784849422544;345.40072973391165;5475.288212532365;5576.609637325389;5643.294588176478;5643.351014633318;5719.628224165437;5596.384397716248;5598.468145526179;5605.103183490643;5627.43725627437;5628.26832511774;4334.3498475228735;4350.8259669613335;4361.389405483219;4365.257906155945;4393;3854.286956912966;4151.335786274197;4259.1268789897995;4330.437043184397;4353.412289340946;4264.616184543391;4268.40242366069;4281.614654681078;4303.978480107595;4330.588594083557;4104.692148088875;4316.82732690692;4345.760983145909;4351.717138386027;4365;3696.722745794076;3718.4050551911837;3744;3746.2507498500136;3805.296020236265;5411.242426060355;5413.864653383697;5415.214793854909;5451.64564303323;5469.398366179717;4739.478657347689;4863.732494712789;4890.633202509826;4924.655274130812;4951.529604687549;5814.389489103625;5825.533957283449;5825.572734679989;5826.126081087871;5853.824385268449;4142.482189726285;4152.418661387408;4163.2298024865495;4177.4778152730905;4192.140611174702;5870.471657550841;6079.179310793031;6126.8774624507705;6133.846653833645;6150.538709596798;4215.346621273646;4216.746995180238;4222.408862759216;4232.576742325804;4268;4299.247631664446;4306.41863348444;4322.481302243751;4322.697411181187;4324.545922678137;4860.392450943632;4939;4979.178435558165;4993.90012199754;5000.524950129732;5857.7949771757785;5865.853353666193;5871.970640146772;5875.970620146872;5883.911741323884;1432.957011289663;1445.2340259662396;1446.2840893757657;1460.758974769151;1465.9518444312155;859.621766422772;913.4747520175792;918.9386258871015;922.4188761080476;925.5372313843078;4982.256210326429;4985.925211121836;5035.928083868837;5038.715127642501;5043.84868453947;5287.365516138011;5296.443873393316;5323.707196104174;5330.440303768126;5343.465653434625;4715.622750179978;5004.574611158092;5019.874503137377;5032.194848824244;5045;2864.26961124916;2867.6235407004338;2877.4820532303966;2886.942261154765;2887.0905664715547;5492.160126357715;5504.633007806681;5545.223668686387;5547.6408279971665;5551.861203469945;5009.674371165863;5400.164913420407;5420.674759514362;5426.75579598919;5430.321209848769;5921.437463440966;6097.207363042776;6188.597741146811;6217.782377616762;6249.78125765596;6050.546209034428;6063.423974722395;6092.543059270483;6094.146819445193;6105.572609917263;5861.710423706948;5976.252968378953;5982.790602328898;6005.579609427298;6018.157457955802;6190.50475961913;6214.316425193316;6225.750969961151;6231.657258850807;6232.968835155795;4259.169461955013;4265.552117027667;4267.93598096035;4287.97856010718;4306.707987603769;5838.708064596838;6028.758849645965;6058.666773327509;6078.3617720138745;6087.299960504599;5927.051671732502;5955.754114007789;5960.76156953717;5968.373320801254;6040.275166979991;5685.60200785941;5795.478406943395;5817.2146760188525;5880.353161152184;5978.760849565969;5875.794347197828;5901.632791734171;5901.901501711635;5905.734241959208;5918.378570250062;5824.854378640568;5954.285485741739;5993.651428428569;6008.188894499187;6016.308124565556;5744;5764.567657425794;5789.073748200268;5832;5842.152887831326 04:11:59 INFO - PROCESS | 28048 | |5;yui.html;803.5660743198673;816.4774544291653;821.5645707774878;840.3193413335197;904.543205681131;14952.177630230333;15165.92417037916;15345.69308613827;15363.078215307089;15418.383264669414;16334.754978675299;16481.423150189745;16504.917475412607;16522.83477165228;16530.917345413287;873.8689196620526;887.1217494680285;898.1602201941173;898.2409863665192;901.715959472764;884.0761404332488;885.2873436883307;887.2148148888216;887.5828360670474;888.0941439731503;946.4415994563207;949.4018768176074;949.7435692363092;949.9097585729379;949.9740280497081;939.9530023498866;940.0693313619536;942.7878727286375;942.8774259346276;943.495230051919;695.0581961442274;696.0603185699316;696.2863065357998;696.3976160621077;696.644711197288;16690.74819388551;16806.24371903255;16852.74720879188;16861.156942152844;16863.40978065774;15285.847141528664;15634.45279415215;16111.355545778271;16115.919420402823;16125;13058.34708264583;13658.658533536642;13681.931590342085;13840.377183026789;14131.576052718436;4079.938800917989;4097.303458412077;4129.070959034223;4216.55726148753;4222.472190976128;63209.57532972694;65763.3423665762;68650.65674671595;70412.6479367605;90963.54518227366;12468.875311246953;12552.121351505308;12661.113722039368;12681.112322137546;12863.871361286454;22036.779632203794;22046.228382006553;22053.669194962087;22057.558848823253;22072.22747203872;7243.529170603894;9386.67146649864;9602.279829012852;9606.807863842683;9634.325597208128;5065.594752419797;5141.146663601737;5203.583713302926;5352.116900711339;5403.405625381205;3839.481669974518;3991.560928297885;3996.820143093568;3998.360262358067;4001.599840015975;629.8992161254178;717.2683862460366;721.2246834652769;725.1914115760994;725.9455540834458;674.2583158525582;680.0113501162364;704.9859002819915;780.75406247032;873.6418068591847;10432;10541.84187237192;10739.785204296026;10823.621173258898;10863.130949524018;726.3535453446436;767.3592550220517;841.0622156295686;845.9492430454193;851.7827953871847;5370.489803468664;5409.702466364309;5428.294321738149;5431.592630552725;5497.367802702661;127566;130590;131121.34439327742;132233.33883330715;132501;73863;110116;110914;112771.43614281875;114183;100372;101774.4911275439;102819;105270.47364763127;106896.46551767191;10033.194025075556;10225.284230104118;10239.539220735234;10361;10482.528286226985;57481.71259143677;57944.71027645004;58357;60153.69923150356;62988.685056574424;80088.59955700184;83584;88241;88464;88985;11876.287422754662;12537.956411828778;12674.989229527597;13273.535426260034;13520.443797018612;13956.930215348859;14039.157650540592;14154.150750954976;14240.35918383696;14241.857581424467;13909.234992075128;14084.929575352468;14319.06926049845;14370;14416.927915360357;14152.363143658355;14166.645833853823;14377.928110359382;14581.927090364481;14742.705145896807;13889.583312500641;13926.93036534811;13947.790783138464;14381.568552943429;14670.413183472541;11613.477393517482;11698.2981021139;11986.400679965793;12138.514459421523;12227.633171004883;11712.765744685228;11818.150189613323;12113.33376664292;12251.69370765738;12365.134440589072 04:11:59 INFO - PROCESS | 28048 | __end_tp_report 04:11:59 INFO - PROCESS | 28048 | __start_cc_report 04:11:59 INFO - PROCESS | 28048 | _x_x_mozilla_cycle_collect,1050 04:11:59 INFO - PROCESS | 28048 | __end_cc_report 04:11:59 INFO - PROCESS | 28048 | __startTimestamp1471518719036__endTimestamp 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | ------- Summary: start ------- 04:11:59 INFO - PROCESS | 28048 | Number of tests: 6 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | [#0] dojo.html Cycles:165 Average:5580.29 Median:5782.51 stddev:641.23 (11.1%) stddev-sans-first:616.62 04:11:59 INFO - PROCESS | 28048 | Values: 3251.3 3256.3 3273.0 3307.9 3318.9 5546.8 5635.3 5646.0 5664.3 5678.7 5637.6 5641.4 5648.8 5655.0 5701.6 4944.9 4964.7 5069.8 5074.6 5119.5 4991.0 5073.5 5090.7 5091.7 5095.1 4976.3 5001.3 5031.6 5038.6 5067.2 5138.3 5157.7 5166.8 5167.6 5180.9 4947.9 4958.4 4977.3 5054.2 5081.9 5562.9 5567.6 5579.9 5600.0 5604.8 6064.9 6073.2 6084.3 6088.8 6093.6 5984.3 6003.0 6028.8 6094.1 6101.6 5633.7 5664.8 5672.5 5686.1 5687.4 5929.9 6183.7 6201.0 6204.9 6209.3 5837.0 5864.6 5881.6 5948.7 5972.1 6019.1 6048.7 6051.4 6062.0 6071.8 5696.4 5973.1 5984.0 5994.7 6004.3 5909.0 5949.9 5959.5 6018.2 6032.1 4594.1 4600.8 4601.9 4603.9 4630.6 4044.2 4113.0 4129.6 4167.7 4172.7 5675.5 5703.2 5705.0 5761.2 5782.5 6109.1 6109.3 6112.5 6114.7 6114.9 5549.3 5738.4 5763.9 5764.0 5766.0 5011.8 5060.7 5060.8 5084.6 5105.6 6252.6 6263.7 6263.7 6264.3 6271.1 5825.1 6039.4 6068.3 6081.8 6089.2 6130.1 6165.0 6175.5 6218.4 6252.2 5864.8 5935.4 5937.5 5941.9 5989.9 5829.0 6048.6 6060.5 6076.3 6079.3 5857.4 5891.8 5911.0 5963.6 5994.5 5865.4 5870.4 5871.9 5877.1 5894.5 5637.7 5814.1 5829.2 5841.7 5842.5 5760.3 5798.1 5829.0 5856.5 5898.6 5703.7 5710.9 5713.6 5714.7 5716.7 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | [#1] ext.html Cycles:180 Average:62511.85 Median:21954.99 stddev:121023.92 (551.2%) stddev-sans-first:121274.13 04:11:59 INFO - PROCESS | 28048 | Values: 594.8 618.1 642.5 663.6 673.8 34675.3 38168.4 38345.4 38372.2 46571.8 44173.3 49371.0 49501.8 49537.8 49574.5 9382.5 9541.1 9559.9 9561.8 9577.1 8675.2 8889.3 9308.6 9401.2 9408.6 11233.2 11486.2 11506.5 11965.6 11968.8 11419.3 11453.3 11454.0 11493.3 11588.2 11093.6 11325.8 11329.2 11332.1 11339.5 46274.8 46666.5 46669.3 46738.3 46759.3 4024.6 4119.1 4207.9 4421.8 4699.5 35476.8 36071.7 36322.4 36643.6 37103.6 4791.4 4855.3 4938.4 4949.3 5018.0 384314.0 384559.1 385292.0 385572.0 385752.0 65907.3 66268.3 66290.3 66293.3 66323.7 40485.8 41210.6 41342.0 43266.1 69637.3 17965.6 18141.5 18316.1 18326.1 18339.5 12981.5 13308.8 13329.6 13397.6 13413.7 18936.9 20499.6 21190.3 21283.3 21474.7 7469.4 7547.8 7692.7 7785.8 7875.7 1064.5 1110.8 1159.2 1216.9 1225.1 34068.7 34224.8 34433.1 34468.0 34661.1 1124.7 1166.4 1224.8 1233.9 1234.9 18220.2 18434.8 18449.5 18613.7 18632.3 586576.0 596002.1 596256.0 597051.0 598433.0 64762.4 65304.7 65455.3 65488.7 65532.0 1354.3 1447.1 1468.6 1492.9 1508.5 35805.3 36916.0 37189.3 37680.0 37811.8 56789.4 57296.4 57303.7 57438.7 57485.4 351077.0 351134.0 351205.0 352040.0 352877.0 31043.4 31779.2 31967.8 32032.0 33561.8 53367.7 54294.7 54866.5 54893.5 55612.4 45899.8 46587.5 46680.0 47547.3 48420.0 45716.8 46006.5 46090.5 46170.5 47803.3 45601.0 46361.8 46472.1 46521.8 47089.8 38394.0 39885.4 40725.8 40956.6 41452.0 28440.5 28884.9 29002.7 29200.0 29334.9 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | [#2] jquery.html Cycles:180 Average:31674.84 Median:13200.33 stddev:92389.84 (699.9%) stddev-sans-first:92619.16 04:11:59 INFO - PROCESS | 28048 | Values: 392.2 412.4 413.1 418.9 434.9 14695.6 19987.9 20610.7 20621.2 20748.3 19710.1 19952.0 19986.2 19991.8 20176.4 5572.2 5593.7 5847.0 5894.7 5912.8 5837.2 5906.1 5910.7 5914.4 5915.9 5589.8 5600.7 5629.6 5633.2 5637.2 5604.1 5655.5 5679.8 5696.4 5703.5 5270.6 5297.2 5309.3 5311.0 5313.9 23048.4 23703.3 23749.4 23881.0 23955.3 2279.5 2350.2 2445.4 2636.6 2695.7 9955.6 10015.0 10207.0 10207.7 13376.7 2010.1 2021.3 2070.8 2085.2 2124.5 87110.0 87951.0 88624.1 89491.1 92045.0 9582.4 9742.5 9817.5 10698.8 10894.0 14636.0 14655.9 14669.7 14693.6 14700.0 7920.0 8196.9 8312.5 8366.6 8542.7 8143.2 8144.8 8200.6 8331.5 8445.8 4386.9 4526.2 4526.9 4591.6 4607.9 1869.6 1876.7 1882.2 1917.2 1977.0 968.4 1014.6 1057.8 1063.6 1083.7 18145.0 19523.5 19598.8 19699.5 19952.0 990.7 993.3 994.9 1025.0 1064.9 10357.4 11000.7 11099.5 11101.7 11160.1 540164.0 561155.0 561302.0 567176.0 574129.0 73328.5 93866.5 98141.5 102706.0 105652.5 1011.6 1019.3 1041.6 1049.9 1051.3 17822.6 17964.3 18104.2 18219.9 18650.7 45386.5 45391.3 45730.8 45943.8 46408.5 62928.4 63765.7 64097.0 64125.7 65146.0 8613.5 8622.5 8820.3 8832.8 8838.8 11607.7 13735.9 13776.7 13934.5 14035.6 11997.2 12144.3 12213.8 12379.9 12543.6 11978.3 12025.5 12195.4 12358.2 12490.4 12168.4 12259.9 12335.4 12414.5 12432.9 8368.4 8598.1 8614.7 8831.5 8840.5 11909.2 12005.9 12137.6 12159.6 12189.3 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | [#3] mootools.html Cycles:180 Average:6432.45 Median:4620.16 stddev:3697.41 (80.0%) stddev-sans-first:3689.62 04:11:59 INFO - PROCESS | 28048 | Values: 1556.4 1612.5 1791.4 1893.1 1904.4 9152.9 9923.1 9926.9 9934.4 9941.3 8391.7 9103.7 9386.3 9401.0 9679.0 2459.0 2493.3 2494.6 2494.8 2495.4 2486.6 2562.7 2566.6 2567.3 2568.3 2528.5 2533.0 2561.1 2568.0 2569.9 2479.5 2481.5 2487.3 2504.4 2504.8 2470.8 2481.7 2485.7 2487.3 2492.8 9611.5 9790.7 9795.6 9797.6 9806.9 5613.0 5756.0 5756.3 5757.9 5758.8 3468.6 3589.3 3646.1 3671.1 3676.2 1107.4 1185.1 1193.6 1198.4 1223.3 14043.4 14073.6 14078.8 14083.6 14126.9 5038.6 5073.5 5210.3 5231.1 5270.2 11658.6 11717.4 11743.9 11787.8 11793.9 5833.4 5850.2 5856.2 5870.5 5870.8 4572.8 4667.5 5198.8 5201.2 5355.7 4366.6 4378.3 4457.2 4487.6 4525.9 1380.1 1394.4 1396.1 1401.6 1419.7 963.3 1003.6 1057.9 1061.0 1073.2 6397.6 6653.8 6730.6 6784.6 6935.0 1020.0 1021.5 1025.8 1049.9 1089.2 4463.5 4730.3 4797.1 4815.2 4916.8 11984.6 12207.7 12348.3 12357.2 12407.4 11444.3 11948.6 11986.9 12148.8 12151.8 9038.1 9167.5 9244.1 9251.1 9259.0 6561.2 6865.0 6873.3 6877.5 6922.9 11057.9 11100.7 11142.9 11222.3 11275.9 10114.8 11212.2 11384.1 11407.4 11529.4 8242.2 8453.3 8545.4 8711.9 8724.9 9640.5 9671.0 9736.0 9744.8 9749.8 8242.5 8291.3 8354.7 8381.1 8491.3 8461.5 8490.6 8518.8 8661.3 8672.3 7713.9 7728.3 7742.5 7763.3 7782.0 5819.3 5851.6 5919.1 5960.9 6049.2 5326.5 5430.3 5487.8 5489.9 5666.4 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | [#4] prototype.html Cycles:180 Average:4826.02 Median:5423.72 stddev:1443.20 (26.6%) stddev-sans-first:1407.13 04:11:59 INFO - PROCESS | 28048 | Values: 323.5 329.0 336.5 343.4 345.4 5475.3 5576.6 5643.3 5643.4 5719.6 5596.4 5598.5 5605.1 5627.4 5628.3 4334.3 4350.8 4361.4 4365.3 4393.0 3854.3 4151.3 4259.1 4330.4 4353.4 4264.6 4268.4 4281.6 4304.0 4330.6 4104.7 4316.8 4345.8 4351.7 4365.0 3696.7 3718.4 3744.0 3746.3 3805.3 5411.2 5413.9 5415.2 5451.6 5469.4 4739.5 4863.7 4890.6 4924.7 4951.5 5814.4 5825.5 5825.6 5826.1 5853.8 4142.5 4152.4 4163.2 4177.5 4192.1 5870.5 6079.2 6126.9 6133.8 6150.5 4215.3 4216.7 4222.4 4232.6 4268.0 4299.2 4306.4 4322.5 4322.7 4324.5 4860.4 4939.0 4979.2 4993.9 5000.5 5857.8 5865.9 5872.0 5876.0 5883.9 1433.0 1445.2 1446.3 1460.8 1466.0 859.6 913.5 918.9 922.4 925.5 4982.3 4985.9 5035.9 5038.7 5043.8 5287.4 5296.4 5323.7 5330.4 5343.5 4715.6 5004.6 5019.9 5032.2 5045.0 2864.3 2867.6 2877.5 2886.9 2887.1 5492.2 5504.6 5545.2 5547.6 5551.9 5009.7 5400.2 5420.7 5426.8 5430.3 5921.4 6097.2 6188.6 6217.8 6249.8 6050.5 6063.4 6092.5 6094.1 6105.6 5861.7 5976.3 5982.8 6005.6 6018.2 6190.5 6214.3 6225.8 6231.7 6233.0 4259.2 4265.6 4267.9 4288.0 4306.7 5838.7 6028.8 6058.7 6078.4 6087.3 5927.1 5955.8 5960.8 5968.4 6040.3 5685.6 5795.5 5817.2 5880.4 5978.8 5875.8 5901.6 5901.9 5905.7 5918.4 5824.9 5954.3 5993.7 6008.2 6016.3 5744.0 5764.6 5789.1 5832.0 5842.2 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - PROCESS | 28048 | [#5] yui.html Cycles:180 Average:22732.56 Median:22049.95 stddev:33443.50 (151.7%) stddev-sans-first:33496.78 04:11:59 INFO - PROCESS | 28048 | Values: 803.6 816.5 821.6 840.3 904.5 14952.2 15165.9 15345.7 15363.1 15418.4 16334.8 16481.4 16504.9 16522.8 16530.9 873.9 887.1 898.2 898.2 901.7 884.1 885.3 887.2 887.6 888.1 946.4 949.4 949.7 949.9 950.0 940.0 940.1 942.8 942.9 943.5 695.1 696.1 696.3 696.4 696.6 16690.7 16806.2 16852.7 16861.2 16863.4 15285.8 15634.5 16111.4 16115.9 16125.0 13058.3 13658.7 13681.9 13840.4 14131.6 4079.9 4097.3 4129.1 4216.6 4222.5 63209.6 65763.3 68650.7 70412.6 90963.5 12468.9 12552.1 12661.1 12681.1 12863.9 22036.8 22046.2 22053.7 22057.6 22072.2 7243.5 9386.7 9602.3 9606.8 9634.3 5065.6 5141.1 5203.6 5352.1 5403.4 3839.5 3991.6 3996.8 3998.4 4001.6 629.9 717.3 721.2 725.2 725.9 674.3 680.0 705.0 780.8 873.6 10432.0 10541.8 10739.8 10823.6 10863.1 726.4 767.4 841.1 845.9 851.8 5370.5 5409.7 5428.3 5431.6 5497.4 127566.0 130590.0 131121.3 132233.3 132501.0 73863.0 110116.0 110914.0 112771.4 114183.0 100372.0 101774.5 102819.0 105270.5 106896.5 10033.2 10225.3 10239.5 10361.0 10482.5 57481.7 57944.7 58357.0 60153.7 62988.7 80088.6 83584.0 88241.0 88464.0 88985.0 11876.3 12538.0 12675.0 13273.5 13520.4 13956.9 14039.2 14154.2 14240.4 14241.9 13909.2 14084.9 14319.1 14370.0 14416.9 14152.4 14166.6 14377.9 14581.9 14742.7 13889.6 13926.9 13947.8 14381.6 14670.4 11613.5 11698.3 11986.4 12138.5 12227.6 11712.8 11818.2 12113.3 12251.7 12365.1 04:11:59 INFO - PROCESS | 28048 | -------- Summary: end -------- 04:11:59 INFO - PROCESS | 28048 | 04:11:59 INFO - TEST-INFO | 28048: exit 0 04:11:59 INFO - TEST-OK | dromaeo_css | took 1112671ms 04:11:59 INFO - TEST-START | kraken 04:11:59 INFO - operating with platform_type : linux_ 04:11:59 INFO - Initialising browser for kraken test... 04:11:59 INFO - TEST-INFO | started process 28249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp63tjLe/profile http://localhost:35418/getInfo.html) 04:12:03 INFO - PROCESS | 28249 | __metrics Screen width/height:1600/1200 04:12:03 INFO - PROCESS | 28249 | colorDepth:24 04:12:03 INFO - PROCESS | 28249 | Browser inner width/height: 1024/697 04:12:03 INFO - PROCESS | 28249 | __metrics 04:12:04 INFO - PROCESS | 28249 | 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" 04:12:04 INFO - PROCESS | 28249 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:12:05 INFO - TEST-INFO | 28249: exit 0 04:12:05 INFO - Browser initialized. 04:12:05 INFO - Running cycle 1/1 for kraken test... 04:12:05 INFO - TEST-INFO | started process 28335 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp63tjLe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:17 INFO - PROCESS | 28335 | RSS: Main: 145350656 04:12:17 INFO - PROCESS | 28335 | 04:12:19 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35418/tests/kraken/driver.html?testName=audio-beat-detection) 04:12:20 INFO - PROCESS | 28335 | RSS: Main: 191905792 04:12:20 INFO - PROCESS | 28335 | 04:12:23 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35418/tests/kraken/driver.html?testName=audio-dft) 04:12:23 INFO - PROCESS | 28335 | RSS: Main: 181911552 04:12:23 INFO - PROCESS | 28335 | 04:12:26 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35418/tests/kraken/driver.html?testName=audio-fft) 04:12:27 INFO - PROCESS | 28335 | RSS: Main: 269574144 04:12:27 INFO - PROCESS | 28335 | 04:12:29 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35418/tests/kraken/driver.html?testName=audio-oscillator) 04:12:29 INFO - PROCESS | 28335 | RSS: Main: 175521792 04:12:29 INFO - PROCESS | 28335 | 04:12:31 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35418/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:12:32 INFO - PROCESS | 28335 | RSS: Main: 177692672 04:12:32 INFO - PROCESS | 28335 | 04:12:35 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35418/tests/kraken/driver.html?testName=imaging-darkroom) 04:12:36 INFO - PROCESS | 28335 | RSS: Main: 212733952 04:12:36 INFO - PROCESS | 28335 | 04:12:39 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35418/tests/kraken/driver.html?testName=imaging-desaturate) 04:12:40 INFO - PROCESS | 28335 | RSS: Main: 211632128 04:12:40 INFO - PROCESS | 28335 | 04:12:42 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35418/tests/kraken/driver.html?testName=json-parse-financial) 04:12:43 INFO - PROCESS | 28335 | RSS: Main: 211603456 04:12:43 INFO - PROCESS | 28335 | 04:12:44 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35418/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:12:45 INFO - PROCESS | 28335 | RSS: Main: 176193536 04:12:45 INFO - PROCESS | 28335 | 04:12:47 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:12:47 INFO - PROCESS | 28335 | RSS: Main: 182501376 04:12:47 INFO - PROCESS | 28335 | 04:12:49 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:12:50 INFO - PROCESS | 28335 | RSS: Main: 186736640 04:12:50 INFO - PROCESS | 28335 | 04:12:52 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:12:53 INFO - PROCESS | 28335 | RSS: Main: 181129216 04:12:53 INFO - PROCESS | 28335 | 04:12:56 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:12:56 INFO - PROCESS | 28335 | RSS: Main: 184578048 04:12:56 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | Cycle 1(1): loaded http://localhost:35418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35418/tests/kraken/driver.html?testName=ai-astar) 04:12:58 INFO - PROCESS | 28335 | RSS: Main: 235859968 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | __start_tp_report 04:12:58 INFO - PROCESS | 28335 | _x_x_mozilla_page_load 04:12:58 INFO - PROCESS | 28335 | _x_x_mozilla_page_load_details 04:12:58 INFO - PROCESS | 28335 | |i|pagename|runs| 04:12:58 INFO - PROCESS | 28335 | |0;ai-astar;101;111;110;112;113;111;111;111;110;109 04:12:58 INFO - PROCESS | 28335 | |1;audio-beat-detection;143;116;117;175;121;117;145;124;148;116 04:12:58 INFO - PROCESS | 28335 | |2;audio-dft;161;159;160;161;161;159;160;160;160;160 04:12:58 INFO - PROCESS | 28335 | |3;audio-fft;75;76;75;111;76;77;76;76;102;75 04:12:58 INFO - PROCESS | 28335 | |4;audio-oscillator;84;83;131;85;84;83;116;84;84;127 04:12:58 INFO - PROCESS | 28335 | |5;imaging-gaussian-blur;117;116;117;117;118;117;117;116;117;116 04:12:58 INFO - PROCESS | 28335 | |6;imaging-darkroom;146;146;145;145;145;146;146;146;145;145 04:12:58 INFO - PROCESS | 28335 | |7;imaging-desaturate;94;96;96;95;95;95;94;95;95;100 04:12:58 INFO - PROCESS | 28335 | |8;json-parse-financial;52;51;53;51;52;52;51;51;51;54 04:12:58 INFO - PROCESS | 28335 | |9;json-stringify-tinderbox;58;61;59;58;59;58;58;59;59;61 04:12:58 INFO - PROCESS | 28335 | |10;stanford-crypto-aes;72;72;72;74;81;75;72;73;72;76 04:12:58 INFO - PROCESS | 28335 | |11;stanford-crypto-ccm;122;121;118;118;117;117;119;116;167;116 04:12:58 INFO - PROCESS | 28335 | |12;stanford-crypto-pbkdf2;161;163;164;165;161;160;158;161;156;160 04:12:58 INFO - PROCESS | 28335 | |13;stanford-crypto-sha256-iterative;63;62;64;62;63;63;63;63;61;64 04:12:58 INFO - PROCESS | 28335 | __end_tp_report 04:12:58 INFO - PROCESS | 28335 | __start_cc_report 04:12:58 INFO - PROCESS | 28335 | _x_x_mozilla_cycle_collect,1618 04:12:58 INFO - PROCESS | 28335 | __end_cc_report 04:12:58 INFO - PROCESS | 28335 | __startTimestamp1471518778778__endTimestamp 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | ------- Summary: start ------- 04:12:58 INFO - PROCESS | 28335 | Number of tests: 14 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:3.31 (3.0%) stddev-sans-first:1.17 04:12:58 INFO - PROCESS | 28335 | Values: 101.0 111.0 110.0 112.0 113.0 111.0 111.0 111.0 110.0 109.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:133.50 stddev:19.84 (14.9%) stddev-sans-first:20.65 04:12:58 INFO - PROCESS | 28335 | Values: 143.0 116.0 117.0 175.0 121.0 117.0 145.0 124.0 148.0 116.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 04:12:58 INFO - PROCESS | 28335 | Values: 161.0 159.0 160.0 161.0 161.0 159.0 160.0 160.0 160.0 160.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:13.15 (17.3%) stddev-sans-first:13.71 04:12:58 INFO - PROCESS | 28335 | Values: 75.0 76.0 75.0 111.0 76.0 77.0 76.0 76.0 102.0 75.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:84.00 stddev:20.06 (23.9%) stddev-sans-first:20.79 04:12:58 INFO - PROCESS | 28335 | Values: 84.0 83.0 131.0 85.0 84.0 83.0 116.0 84.0 84.0 127.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 04:12:58 INFO - PROCESS | 28335 | Values: 117.0 116.0 117.0 117.0 118.0 117.0 117.0 116.0 117.0 116.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 04:12:58 INFO - PROCESS | 28335 | Values: 146.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 145.0 145.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:1.72 (1.8%) stddev-sans-first:1.73 04:12:58 INFO - PROCESS | 28335 | Values: 94.0 96.0 96.0 95.0 95.0 95.0 94.0 95.0 95.0 100.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.09 04:12:58 INFO - PROCESS | 28335 | Values: 52.0 51.0 53.0 51.0 52.0 52.0 51.0 51.0 51.0 54.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:1.15 (2.0%) stddev-sans-first:1.17 04:12:58 INFO - PROCESS | 28335 | Values: 58.0 61.0 59.0 58.0 59.0 58.0 58.0 59.0 59.0 61.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:73.50 stddev:2.88 (3.9%) stddev-sans-first:2.98 04:12:58 INFO - PROCESS | 28335 | Values: 72.0 72.0 72.0 74.0 81.0 75.0 72.0 73.0 72.0 76.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:118.50 stddev:15.55 (13.1%) stddev-sans-first:16.49 04:12:58 INFO - PROCESS | 28335 | Values: 122.0 121.0 118.0 118.0 117.0 117.0 119.0 116.0 167.0 116.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:161.00 stddev:2.69 (1.7%) stddev-sans-first:2.85 04:12:58 INFO - PROCESS | 28335 | Values: 161.0 163.0 164.0 165.0 161.0 160.0 158.0 161.0 156.0 160.0 04:12:58 INFO - PROCESS | 28335 | 04:12:58 INFO - PROCESS | 28335 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 04:12:58 INFO - PROCESS | 28335 | Values: 63.0 62.0 64.0 62.0 63.0 63.0 63.0 63.0 61.0 64.0 04:12:58 INFO - PROCESS | 28335 | -------- Summary: end -------- 04:12:58 INFO - PROCESS | 28335 | 04:12:59 INFO - TEST-INFO | 28335: exit 0 04:12:59 INFO - TEST-OK | kraken | took 60002ms 04:12:59 INFO - SUITE-END | took 1172s 04:12:59 INFO - Completed test suite (00:19:32) 04:12:59 INFO - javascript benchmark 04:12:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.3172233830896, 3256.251062255681, 3272.967270327296, 3307.8676852925882, 3318.917027074323, 5546.77812887484, 5635.3237611486575, 5645.971770141148, 5664.2636457260605, 5678.659280443171, 5637.577181711367, 5641.351244606872, 5648.774049038044, 5654.971725141369, 5701.600887937846, 4944.9258261126115, 4964.677295975768, 5069.771860266297, 5074.61940354474, 5119.462456442076, 4991.0, 5073.4926507349155, 5090.669106508084, 5091.745412729368, 5095.082885080684, 4976.278439626252, 5001.349824522807, 5031.597472202233, 5038.5969122470115, 5067.163917953533, 5138.332016837825, 5157.742112894341, 5166.819161329373, 5167.586593072564, 5180.896382072357, 4947.851564453073, 4958.380202474691, 4977.253411988195, 5054.21659642756, 5081.872953176163, 5562.888742225133, 5567.582431317627, 5579.8605034874045, 5600.0, 5604.775808967677, 6064.854867098253, 6073.237863496781, 6084.26988761347, 6088.756449742005, 6093.6039157454625, 5984.311804142492, 6003.0, 6028.788992385245, 6094.058517321852, 6101.572889897709, 5633.718314084321, 5664.773409063632, 5672.517835983905, 5686.09022556389, 5687.402822703557, 5929.911051334234, 6183.690815459209, 6201.0, 6204.875902481925, 6209.316975132731, 5837.036888913367, 5864.648121112747, 5881.558883083786, 5948.672822994777, 5972.074328479092, 6019.067044608093, 6048.6673232971725, 6051.394860514001, 6062.0, 6071.787487438007, 5696.3733989261145, 5973.133895585116, 5984.0, 5994.700264986734, 6004.279486461653, 5908.970455147697, 5949.881002379928, 5959.493443057302, 6018.187544681499, 6032.095185722089, 4594.081183763261, 4600.7929643166135, 4601.930971035437, 4603.861884143481, 4630.629549636021, 4044.211378781168, 4112.979435102805, 4129.607687269705, 4167.6874234432535, 4172.70791044624, 5675.460831221028, 5703.17303990919, 5705.0, 5761.222234998306, 5782.508486778671, 6109.114178444101, 6109.266887973472, 6112.541559383064, 6114.685203258513, 6114.877702445926, 5549.278593782863, 5738.397468265772, 5763.94236057634, 5764.0, 5765.971170144123, 5011.824586139468, 5060.7216603086445, 5060.797568097235, 5084.6440749147205, 5105.591552675852, 6252.624842509464, 6263.655498947602, 6263.749450022039, 6264.342244064308, 6271.090691849657, 5825.126231065374, 6039.4262545058145, 6068.332483426908, 6081.787137450168, 6089.238845144357, 6130.141780150871, 6164.969175154275, 6175.505959523138, 6218.409251121137, 6252.187215661935, 5864.824055278348, 5935.436133567304, 5937.495312898279, 5941.940580594139, 5989.940100598938, 5829.0, 6048.637081775108, 6060.454559089703, 6076.33160352352, 6079.300880398634, 5857.414258574108, 5891.793787217427, 5910.970445147746, 5963.642181469126, 5994.520438364833, 5865.354810970704, 5870.442307980735, 5871.94128058714, 5877.059670452707, 5894.498967587803, 5637.746301416364, 5814.098814683731, 5829.213056237354, 5841.678707671119, 5842.503387211965, 5760.251167348218, 5798.10129429939, 5829.038208695687, 5856.502197313276, 5898.587098903035, 5703.714814259353, 5710.942890571041, 5713.600047996601, 5714.657120572779, 5716.657000579979], "value": 5536.930049499711, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.7620951619351, 618.1222663817379, 642.5149012495565, 663.5620490476283, 673.7675501951825, 34675.306493870106, 38168.427473587886, 38345.424818627675, 38372.23255534891, 46571.76714116433, 44173.337399939024, 49371.0, 49501.75249123758, 49537.75231123839, 49574.50425495744, 9382.530873456317, 9541.141297283242, 9559.904400956006, 9561.80876382472, 9577.138057574817, 8675.219230269275, 8889.333300002494, 9308.58111384986, 9401.153896149344, 9408.576614052385, 11233.21367504275, 11486.195966282363, 11506.482208300604, 11965.641030769091, 11968.82046769299, 11419.257748246337, 11453.312801231952, 11454.02640775531, 11493.252938559008, 11588.246763960316, 11093.611723589636, 11325.773484530264, 11329.15031372642, 11332.093432525378, 11339.489722962486, 46274.768626156656, 46666.53333466656, 46669.29996050062, 46738.298925516145, 46759.29861052087, 4024.5774193709826, 4119.114390406077, 4207.852725154636, 4421.823127074913, 4699.530046995307, 35476.82261588702, 36071.72248684485, 36322.36095346184, 36643.63356366455, 37103.62896371056, 4791.4250289965075, 4855.295982082614, 4938.407391113089, 4949.282354058641, 5018.0, 384314.0, 384559.077204615, 385291.9999999972, 385572.0, 385752.0, 65907.34092659107, 66268.33731662622, 66290.33709662939, 66293.33706662968, 66323.66838165875, 40485.79757101196, 41210.587894120676, 41342.0, 43266.13467730691, 69637.3036269641, 17965.640687186184, 18141.45575632733, 18316.084195790158, 18326.083695815156, 18339.54151146232, 12981.545645902348, 13308.800367994489, 13329.600111996655, 13397.598072057854, 13413.731725365438, 18936.90531547361, 20499.590008199753, 21190.258340957993, 21283.255086072222, 21474.677879831503, 7469.402447804163, 7547.773566793005, 7692.730754423568, 7785.766427007199, 7875.684972601146, 1064.5156453813497, 1110.7500812317164, 1159.171192597297, 1216.8904798568171, 1225.1424003197797, 34068.65931340704, 34224.82887585596, 34433.13917152091, 34468.0, 34661.13347166341, 1124.6963319903743, 1166.416791604198, 1224.834647322618, 1233.8827811357908, 1234.919730217533, 18220.180091895898, 18434.81565184358, 18449.53876153107, 18613.7207941881, 18632.25470981173, 586576.0, 596002.0599691009, 596256.0, 597051.0, 598433.0, 64762.35237647658, 65304.67347663231, 65455.34544654588, 65488.67255663787, 65532.0, 1354.3025341948903, 1447.117258472331, 1468.6401831551339, 1492.8731057860205, 1508.547435769265, 35805.283894322485, 36916.0, 37189.25621487609, 37680.0, 37811.81094094622, 56789.43210568007, 57296.42703572911, 57303.713481433995, 57438.7128064357, 57485.42514574968, 351077.0, 351134.0, 351205.0, 352040.0, 352877.0, 31043.37913241768, 31779.20551986219, 31967.840160799977, 32032.039038828872, 33561.83219083889, 53367.7331613355, 54294.72852635712, 54866.451335486134, 54893.45106549043, 55612.443875560726, 45899.77050114728, 46587.53412465967, 46680.0, 47547.28679069748, 48420.03159936711, 45716.77141614271, 46006.53993460022, 46090.539094609965, 46170.53829461662, 47803.28295075646, 45601.0, 46361.76819115883, 46472.070558589316, 46521.76739116283, 47089.76455117839, 38394.0, 39885.40171897482, 40725.796371017954, 40956.56652017165, 41452.0, 28440.530554427132, 28884.855575721987, 29002.709972900844, 29200.0, 29334.853325733235], "value": 21969.097479272106, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.1529496288018, 412.4205491284745, 413.1262380066161, 418.89946412860905, 434.9478062632485, 14695.559133226006, 19987.900060499713, 20610.690839637417, 20621.17515299388, 20748.27381041664, 19710.113044912978, 19952.0, 19986.20055197794, 19991.800081999176, 20176.394708158743, 5572.247746554204, 5593.720313984295, 5847.0, 5894.734736936826, 5912.763489460416, 5837.241158649391, 5906.143609176666, 5910.704464776765, 5914.4381283777975, 5915.940840591582, 5589.832305030835, 5600.663960162383, 5629.577781666371, 5633.239512665799, 5637.182608521783, 5604.103343465026, 5655.519280861132, 5679.772809087653, 5696.430356964312, 5703.54371650267, 5270.578353731692, 5297.152455607084, 5309.309789727349, 5311.0, 5313.946860531384, 23048.42378940523, 23703.288901332988, 23749.406264843343, 23881.0, 23955.281341559607, 2279.464325883416, 2350.21267875262, 2445.3886528367907, 2636.6308716779668, 2695.7169497202904, 9955.601775928953, 10015.048570385801, 10207.0, 10207.744806379826, 13376.729210725063, 2010.0854111379288, 2021.3026505855457, 2070.8136267735977, 2085.18677715691, 2124.511362386644, 87109.99999999936, 87951.0, 88624.11375886224, 89491.10508894893, 92045.0, 9582.425054496753, 9742.512874356396, 9817.460039697742, 10698.839517407245, 10894.0, 14636.0, 14655.853441465662, 14669.706605867823, 14693.55919322422, 14700.0, 7920.0, 8196.877046844302, 8312.501249924902, 8366.623501942428, 8542.658293668308, 8143.185681431928, 8144.83710325802, 8200.58997050145, 8331.458455200393, 8445.788855278544, 4386.934195987063, 4526.23054080807, 4526.864194074117, 4591.586757191869, 4607.861764147081, 1869.5793446474433, 1876.7091100879386, 1882.2376937340398, 1917.1978340360295, 1977.0, 968.4189486308159, 1014.573878970834, 1057.7567159553346, 1063.6436793674206, 1083.6803143072812, 18145.0, 19523.51191220231, 19598.8040119597, 19699.50751231202, 19952.0, 990.7374545745383, 993.2749093162032, 994.9303548751662, 1025.0, 1064.9307794993301, 10357.4303413313, 11000.724981875517, 11099.500522476508, 11101.72245693848, 11160.107191424666, 540164.0, 561155.0, 561302.0, 567176.0, 574129.0, 73328.53342933218, 93866.53066734759, 98141.5092924545, 102706.0, 105652.47173764235, 1011.6206422591529, 1019.2966852871494, 1041.5937784264142, 1049.895010498944, 1051.279873286801, 17822.643547128726, 17964.281428742703, 18104.18531166127, 18219.90890045541, 18650.72023919615, 45386.546134538235, 45391.319130213735, 45730.771346143054, 45943.77028114838, 46408.53591464042, 62928.37071629225, 63765.681171593846, 64097.0, 64125.67937160284, 65146.0, 8613.48319100856, 8622.525761083201, 8820.250278726124, 8832.779180520538, 8838.823223535623, 11607.709807254887, 13735.931320343334, 13776.724465510833, 13934.512292069729, 14035.649108772363, 11997.220180688228, 12144.271343719405, 12213.816792748294, 12379.93810030944, 12543.623691289276, 11978.281303121841, 12025.518979240733, 12195.390230488618, 12358.196717213352, 12490.375481226085, 12168.391580421121, 12259.877401225873, 12335.3832308386, 12414.503419863104, 12432.937835310766, 8368.372372072241, 8598.140185981352, 8614.65541378338, 8831.470111793313, 8840.469571825712, 11909.166358355178, 12005.939970300093, 12137.635870923887, 12159.635210943687, 12189.268643881396], "value": 9465.193712813114, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1556.3618916244343, 1612.459825958304, 1791.373019443195, 1893.100777130862, 1904.4001139641011, 9152.908470915288, 9923.106920377164, 9926.85109723353, 9934.40393576385, 9941.304108712375, 8391.664333426656, 9103.681371152012, 9386.29602779791, 9401.012893646172, 9679.0, 2458.975410245897, 2493.289412517433, 2494.550980823451, 2494.775470207681, 2495.3512086857368, 2486.577281862079, 2562.7309132541095, 2566.6278389633585, 2567.26832852637, 2568.268043607572, 2528.51958127956, 2532.962005569909, 2561.129216066537, 2568.024150822685, 2569.9486010279784, 2479.5412848623064, 2481.5036992601463, 2487.2786891801356, 2504.3989442533843, 2504.8246622736415, 2470.7899828514596, 2481.6773819403543, 2485.652008718781, 2487.2662564543502, 2492.800575953919, 9611.471369074698, 9790.657326993593, 9795.559199836025, 9797.559109840004, 9806.90193098067, 5612.971935140339, 5756.02147634903, 5756.251687280668, 5757.884842303172, 5758.827235182951, 3468.601110872257, 3589.2642008388216, 3646.106703857545, 3671.1005803578028, 3676.2279921216577, 1107.3521989636072, 1185.0637995983163, 1193.576280420447, 1198.4427241332821, 1223.2966044524346, 14043.368048437846, 14073.577792666236, 14078.788818167735, 14083.577492675236, 14126.858731412656, 5038.5969122470115, 5073.543381095719, 5210.322658054504, 5231.084560201949, 5270.235815806686, 11658.591949281905, 11717.355545444912, 11743.88256117445, 11787.764244715057, 11793.941030294794, 5833.445822646841, 5850.23946886902, 5856.209411729362, 5870.471657550841, 5870.823875283749, 4572.839950601713, 4667.486576476585, 5198.766055527511, 5201.245819356172, 5355.651882627617, 4366.563343665672, 4378.321360189176, 4457.153140903218, 4487.573680500413, 4525.886852828706, 1380.0822453068597, 1394.3864699532173, 1396.0995158122953, 1401.5515035188641, 1419.7160567886258, 963.2871674960479, 1003.5734812704571, 1057.8677665291839, 1060.9628662996759, 1073.1790180511914, 6397.648129352837, 6653.800385988428, 6730.629815360202, 6784.559003664746, 6934.965325173342, 1019.9643012494527, 1021.5403068619152, 1025.7640742629087, 1049.9265051446478, 1089.2484185911696, 4463.486699029653, 4730.337752714623, 4797.112534181118, 4815.2054910939305, 4916.827911023097, 11984.58053968107, 12207.694807629703, 12348.320842353578, 12357.196782209128, 12407.44166512509, 11444.313341199555, 11948.581799636971, 11986.88013119875, 12148.817767733492, 12151.756964860653, 9038.141376569216, 9167.495787731605, 9244.075592440837, 9251.07489251083, 9258.95370523143, 6561.174062796234, 6864.965675171592, 6873.25510923899, 6877.50954245778, 6922.930770692228, 11057.889421106007, 11100.722481938015, 11142.888571114185, 11222.326660400402, 11275.943620281847, 10114.848277275994, 11212.215144939775, 11384.14618903569, 11407.429628518375, 11529.423528823358, 8242.17578242171, 8453.281471075028, 8545.444546104483, 8711.91288087111, 8724.869126962974, 9640.469774162488, 9670.951645241728, 9736.0, 9744.805103898025, 9749.756256093655, 8242.546659933761, 8291.253787159185, 8354.665813367397, 8381.078081411162, 8491.278241349555, 8461.450005749606, 8490.575471226537, 8518.78703032429, 8661.307095432225, 8672.349573781865, 7713.884291735741, 7728.26581474759, 7742.535447873146, 7763.301302882767, 7781.9610901945125, 5819.330776960534, 5851.61964472308, 5919.082542205965, 5960.940390596039, 6049.153118563317, 5326.493983071602, 5430.294061771944, 5487.835364939058, 5489.945100549103, 5666.376698563237], "value": 5119.002060185167, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.5324955439391, 328.99013029609114, 336.45158391821326, 343.3784849422544, 345.40072973391165, 5475.288212532365, 5576.609637325389, 5643.294588176478, 5643.351014633318, 5719.628224165437, 5596.384397716248, 5598.468145526179, 5605.103183490643, 5627.43725627437, 5628.26832511774, 4334.3498475228735, 4350.8259669613335, 4361.389405483219, 4365.257906155945, 4393.0, 3854.286956912966, 4151.335786274197, 4259.1268789897995, 4330.437043184397, 4353.412289340946, 4264.616184543391, 4268.40242366069, 4281.614654681078, 4303.978480107595, 4330.588594083557, 4104.692148088875, 4316.82732690692, 4345.760983145909, 4351.717138386027, 4365.0, 3696.722745794076, 3718.4050551911837, 3744.0, 3746.2507498500136, 3805.296020236265, 5411.242426060355, 5413.864653383697, 5415.214793854909, 5451.64564303323, 5469.398366179717, 4739.478657347689, 4863.732494712789, 4890.633202509826, 4924.655274130812, 4951.529604687549, 5814.389489103625, 5825.533957283449, 5825.572734679989, 5826.126081087871, 5853.824385268449, 4142.482189726285, 4152.418661387408, 4163.2298024865495, 4177.4778152730905, 4192.140611174702, 5870.471657550841, 6079.179310793031, 6126.8774624507705, 6133.846653833645, 6150.538709596798, 4215.346621273646, 4216.746995180238, 4222.408862759216, 4232.576742325804, 4268.0, 4299.247631664446, 4306.41863348444, 4322.481302243751, 4322.697411181187, 4324.545922678137, 4860.392450943632, 4939.0, 4979.178435558165, 4993.90012199754, 5000.524950129732, 5857.7949771757785, 5865.853353666193, 5871.970640146772, 5875.970620146872, 5883.911741323884, 1432.957011289663, 1445.2340259662396, 1446.2840893757657, 1460.758974769151, 1465.9518444312155, 859.621766422772, 913.4747520175792, 918.9386258871015, 922.4188761080476, 925.5372313843078, 4982.256210326429, 4985.925211121836, 5035.928083868837, 5038.715127642501, 5043.84868453947, 5287.365516138011, 5296.443873393316, 5323.707196104174, 5330.440303768126, 5343.465653434625, 4715.622750179978, 5004.574611158092, 5019.874503137377, 5032.194848824244, 5045.0, 2864.26961124916, 2867.6235407004338, 2877.4820532303966, 2886.942261154765, 2887.0905664715547, 5492.160126357715, 5504.633007806681, 5545.223668686387, 5547.6408279971665, 5551.861203469945, 5009.674371165863, 5400.164913420407, 5420.674759514362, 5426.75579598919, 5430.321209848769, 5921.437463440966, 6097.207363042776, 6188.597741146811, 6217.782377616762, 6249.78125765596, 6050.546209034428, 6063.423974722395, 6092.543059270483, 6094.146819445193, 6105.572609917263, 5861.710423706948, 5976.252968378953, 5982.790602328898, 6005.579609427298, 6018.157457955802, 6190.50475961913, 6214.316425193316, 6225.750969961151, 6231.657258850807, 6232.968835155795, 4259.169461955013, 4265.552117027667, 4267.93598096035, 4287.97856010718, 4306.707987603769, 5838.708064596838, 6028.758849645965, 6058.666773327509, 6078.3617720138745, 6087.299960504599, 5927.051671732502, 5955.754114007789, 5960.76156953717, 5968.373320801254, 6040.275166979991, 5685.60200785941, 5795.478406943395, 5817.2146760188525, 5880.353161152184, 5978.760849565969, 5875.794347197828, 5901.632791734171, 5901.901501711635, 5905.734241959208, 5918.378570250062, 5824.854378640568, 5954.285485741739, 5993.651428428569, 6008.188894499187, 6016.308124565556, 5744.0, 5764.567657425794, 5789.073748200268, 5832.0, 5842.152887831326], "value": 4358.3385269611435, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.5660743198673, 816.4774544291653, 821.5645707774878, 840.3193413335197, 904.543205681131, 14952.177630230333, 15165.92417037916, 15345.69308613827, 15363.078215307089, 15418.383264669414, 16334.754978675299, 16481.423150189745, 16504.917475412607, 16522.83477165228, 16530.917345413287, 873.8689196620526, 887.1217494680285, 898.1602201941173, 898.2409863665192, 901.715959472764, 884.0761404332488, 885.2873436883307, 887.2148148888216, 887.5828360670474, 888.0941439731503, 946.4415994563207, 949.4018768176074, 949.7435692363092, 949.9097585729379, 949.9740280497081, 939.9530023498866, 940.0693313619536, 942.7878727286375, 942.8774259346276, 943.495230051919, 695.0581961442274, 696.0603185699316, 696.2863065357998, 696.3976160621077, 696.644711197288, 16690.74819388551, 16806.24371903255, 16852.74720879188, 16861.156942152844, 16863.40978065774, 15285.847141528664, 15634.45279415215, 16111.355545778271, 16115.919420402823, 16125.0, 13058.34708264583, 13658.658533536642, 13681.931590342085, 13840.377183026789, 14131.576052718436, 4079.938800917989, 4097.303458412077, 4129.070959034223, 4216.55726148753, 4222.472190976128, 63209.57532972694, 65763.3423665762, 68650.65674671595, 70412.6479367605, 90963.54518227366, 12468.875311246953, 12552.121351505308, 12661.113722039368, 12681.112322137546, 12863.871361286454, 22036.779632203794, 22046.228382006553, 22053.669194962087, 22057.558848823253, 22072.22747203872, 7243.529170603894, 9386.67146649864, 9602.279829012852, 9606.807863842683, 9634.325597208128, 5065.594752419797, 5141.146663601737, 5203.583713302926, 5352.116900711339, 5403.405625381205, 3839.481669974518, 3991.560928297885, 3996.820143093568, 3998.360262358067, 4001.599840015975, 629.8992161254178, 717.2683862460366, 721.2246834652769, 725.1914115760994, 725.9455540834458, 674.2583158525582, 680.0113501162364, 704.9859002819915, 780.75406247032, 873.6418068591847, 10432.0, 10541.84187237192, 10739.785204296026, 10823.621173258898, 10863.130949524018, 726.3535453446436, 767.3592550220517, 841.0622156295686, 845.9492430454193, 851.7827953871847, 5370.489803468664, 5409.702466364309, 5428.294321738149, 5431.592630552725, 5497.367802702661, 127566.0, 130590.0, 131121.34439327742, 132233.33883330715, 132501.0, 73863.0, 110116.0, 110914.0, 112771.43614281875, 114183.0, 100372.0, 101774.4911275439, 102819.0, 105270.47364763127, 106896.46551767191, 10033.194025075556, 10225.284230104118, 10239.539220735234, 10361.0, 10482.528286226985, 57481.71259143677, 57944.71027645004, 58357.0, 60153.69923150356, 62988.685056574424, 80088.59955700184, 83584.0, 88241.0, 88464.0, 88985.0, 11876.287422754662, 12537.956411828778, 12674.989229527597, 13273.535426260034, 13520.443797018612, 13956.930215348859, 14039.157650540592, 14154.150750954976, 14240.35918383696, 14241.857581424467, 13909.234992075128, 14084.929575352468, 14319.06926049845, 14370.0, 14416.927915360357, 14152.363143658355, 14166.645833853823, 14377.928110359382, 14581.927090364481, 14742.705145896807, 13889.583312500641, 13926.93036534811, 13947.790783138464, 14381.568552943429, 14670.413183472541, 11613.477393517482, 11698.2981021139, 11986.400679965793, 12138.514459421523, 12227.633171004883, 11712.765744685228, 11818.150189613323, 12113.33376664292, 12251.69370765738, 12365.134440589072], "value": 8206.42388706643, "unit": "score"}], "name": "dromaeo_css", "value": 7714.701283548414, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 110.0, 112.0, 113.0, 111.0, 111.0, 111.0, 110.0, 109.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 117.0, 175.0, 121.0, 117.0, 145.0, 124.0, 148.0, 116.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 161.0, 161.0, 159.0, 160.0, 160.0, 160.0, 160.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 111.0, 76.0, 77.0, 76.0, 76.0, 102.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 131.0, 85.0, 84.0, 83.0, 116.0, 84.0, 84.0, 127.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 100.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 51.0, 52.0, 52.0, 51.0, 51.0, 51.0, 54.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 59.0, 58.0, 59.0, 58.0, 58.0, 59.0, 59.0, 61.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 74.0, 81.0, 75.0, 72.0, 73.0, 72.0, 76.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 118.0, 118.0, 117.0, 117.0, 119.0, 116.0, 167.0, 116.0], "value": 123.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 164.0, 165.0, 161.0, 160.0, 158.0, 161.0, 156.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 64.0, 62.0, 63.0, 63.0, 63.0, 63.0, 61.0, 64.0], "value": 62.8, "unit": "score"}], "name": "kraken", "value": 1469.5, "alertThreshold": 2.0}]} 04:13:00 INFO - Return code: 0 04:13:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:13:00 INFO - # TBPL SUCCESS # 04:13:00 INFO - Running post-action listener: _resource_record_post_action 04:13:00 INFO - [mozharness: 2016-08-18 11:13:00.069851Z] Finished run-tests step (success) 04:13:00 INFO - Running post-run listener: _resource_record_post_run 04:13:00 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328437760; Read time: 16; Write time: 207304 04:13:00 INFO - TinderboxPrint: CPU usage
13.0% 04:13:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:13:00 INFO - TinderboxPrint: I/O write bytes / time
328,437,760 / 207,304 04:13:00 INFO - TinderboxPrint: CPU idle
8,264.6 (87.0%) 04:13:00 INFO - TinderboxPrint: CPU user
1,187.0 (12.5%) 04:13:00 INFO - TinderboxPrint: Swap in / out
0 / 0 04:13:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79503360; Read time: 0; Write time: 67588 04:13:00 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 248934400; Read time: 16; Write time: 139716 04:13:00 INFO - Running post-run listener: _upload_blobber_files 04:13:00 INFO - Blob upload gear active. 04:13:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:13:00 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:13:00 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:13:00 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:13:01 INFO - (blobuploader) - INFO - Open directory for files ... 04:13:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:13:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:13:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:02 INFO - (blobuploader) - INFO - Done attempting. 04:13:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:13:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:13:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:07 INFO - (blobuploader) - INFO - Done attempting. 04:13:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:13:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:13:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:13:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:13:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:13:08 INFO - (blobuploader) - INFO - Done attempting. 04:13:08 INFO - (blobuploader) - INFO - Iteration through files over. 04:13:08 INFO - Return code: 0 04:13:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:13:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:13:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c707f9f1e93a1aded6dca62eef1931f6b8d3c5876fa14e9e196174c08eb6aaa39030add7231374cffdaa822dbda42ec01dd4415431773c6b1dd878d44ef91fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6505f5ea7622f313a19a4c86f7dd2064c2468069a1539592df913a5eea284c4bbb5b13c97f405f1a5b3121777c832d484e5209340bbc481a54133b233711de3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07dbcd7898442ab82b215e3c222072c80d72d3207249a3c39e38f94b4f70e1d582745dce594a83a7893b1e3a266f58e5bfdcf167089301bd6244d3a52a2ebb0e"} 04:13:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:13:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:13:08 INFO - Contents: 04:13:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4c707f9f1e93a1aded6dca62eef1931f6b8d3c5876fa14e9e196174c08eb6aaa39030add7231374cffdaa822dbda42ec01dd4415431773c6b1dd878d44ef91fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6505f5ea7622f313a19a4c86f7dd2064c2468069a1539592df913a5eea284c4bbb5b13c97f405f1a5b3121777c832d484e5209340bbc481a54133b233711de3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07dbcd7898442ab82b215e3c222072c80d72d3207249a3c39e38f94b4f70e1d582745dce594a83a7893b1e3a266f58e5bfdcf167089301bd6244d3a52a2ebb0e"} 04:13:08 INFO - Running post-run listener: copy_logs_to_upload_dir 04:13:08 INFO - Copying logs to upload dir... 04:13:08 INFO - mkdir: /builds/slave/test/build/upload/logs 04:13:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.595583 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-08-18 04:13:07.660957) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:13:07.664062) ========= 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-1471517106.477723-791055313 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/4c707f9f1e93a1aded6dca62eef1931f6b8d3c5876fa14e9e196174c08eb6aaa39030add7231374cffdaa822dbda42ec01dd4415431773c6b1dd878d44ef91fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6505f5ea7622f313a19a4c86f7dd2064c2468069a1539592df913a5eea284c4bbb5b13c97f405f1a5b3121777c832d484e5209340bbc481a54133b233711de3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07dbcd7898442ab82b215e3c222072c80d72d3207249a3c39e38f94b4f70e1d582745dce594a83a7893b1e3a266f58e5bfdcf167089301bd6244d3a52a2ebb0e"} build_url:https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/Lrz76RxxQ-CJWYAYHohH2A/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/4c707f9f1e93a1aded6dca62eef1931f6b8d3c5876fa14e9e196174c08eb6aaa39030add7231374cffdaa822dbda42ec01dd4415431773c6b1dd878d44ef91fe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6505f5ea7622f313a19a4c86f7dd2064c2468069a1539592df913a5eea284c4bbb5b13c97f405f1a5b3121777c832d484e5209340bbc481a54133b233711de3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07dbcd7898442ab82b215e3c222072c80d72d3207249a3c39e38f94b4f70e1d582745dce594a83a7893b1e3a266f58e5bfdcf167089301bd6244d3a52a2ebb0e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 04:13:07.689617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:13:07.689970) ========= 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-1471517106.477723-791055313 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004350 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 04:13:07.773504) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:13:07.773843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 04:13:07.774124) ========= ========= Total master_lag: 0.17 =========