builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1469009123.63 results: success (0) buildid: 20160720020121 builduid: 1e3a1a8e47214f6dbfeed352286c3176 revision: 3383b0da1a14340ec6096aca542eb73b0f7341d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.626306) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.626780) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.627092) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.651067) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.651376) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QY2VLXHYOv/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-20 03:05:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.21M=0.001s 2016-07-20 03:05:23 (8.21 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082404 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.755418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.755736) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QY2VLXHYOv/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021397 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:23.805036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 03:05:23.805395) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3383b0da1a14340ec6096aca542eb73b0f7341d5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3383b0da1a14340ec6096aca542eb73b0f7341d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QY2VLXHYOv/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-20 03:05:23,878 truncating revision to first 12 chars 2016-07-20 03:05:23,878 Setting DEBUG logging. 2016-07-20 03:05:23,878 attempt 1/10 2016-07-20 03:05:23,878 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3383b0da1a14?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 03:05:24,933 unpacking tar archive at: mozilla-central-3383b0da1a14/testing/mozharness/ program finished with exit code 0 elapsedTime=1.322586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-20 03:05:25.150051) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:25.150433) ========= script_repo_revision: 3383b0da1a14340ec6096aca542eb73b0f7341d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:25.150901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:25.151209) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 03:05:25.169628) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-20 03:05:25.170051) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QY2VLXHYOv/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:05:25 INFO - MultiFileLogger online at 20160720 03:05:25 in /builds/slave/test 03:05:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:05:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:05:25 INFO - {'append_to_log': False, 03:05:25 INFO - 'base_work_dir': '/builds/slave/test', 03:05:25 INFO - 'blob_upload_branch': 'Firefox', 03:05:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:05:25 INFO - 'branch': 'Firefox', 03:05:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:05:25 INFO - 'config_files': ('talos/mac_config.py',), 03:05:25 INFO - 'default_actions': ('clobber', 03:05:25 INFO - 'read-buildbot-config', 03:05:25 INFO - 'download-and-extract', 03:05:25 INFO - 'populate-webroot', 03:05:25 INFO - 'create-virtualenv', 03:05:25 INFO - 'install', 03:05:25 INFO - 'run-tests'), 03:05:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:05:25 INFO - 'download_minidump_stackwalk': True, 03:05:25 INFO - 'download_symbols': 'ondemand', 03:05:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:05:25 INFO - 'tooltool.py': '/tools/tooltool.py', 03:05:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:05:25 INFO - '/tools/misc-python/virtualenv.py')}, 03:05:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:05:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:05:25 INFO - 'installer_path': 'installer.exe', 03:05:25 INFO - 'log_level': 'info', 03:05:25 INFO - 'log_name': 'talos', 03:05:25 INFO - 'log_to_console': True, 03:05:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:05:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:05:25 INFO - 'opt_config_files': (), 03:05:25 INFO - 'pip_index': False, 03:05:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:05:25 INFO - 'cmd': ('bash', 03:05:25 INFO - '-c', 03:05:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:05:25 INFO - 'enabled': True, 03:05:25 INFO - 'halt_on_failure': False, 03:05:25 INFO - 'name': 'check_screen_resolution'},), 03:05:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:05:25 INFO - 'cmd': ('bash', 03:05:25 INFO - '-c', 03:05:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:05:25 INFO - 'enabled': True, 03:05:25 INFO - 'halt_on_failure': False, 03:05:25 INFO - 'name': 'check_screen_resolution'},), 03:05:25 INFO - 'run_cmd_checks_enabled': True, 03:05:25 INFO - 'sps_profile': False, 03:05:25 INFO - 'sps_profile_interval': 0, 03:05:25 INFO - 'suite': 'dromaeojs', 03:05:25 INFO - 'system_bits': '32', 03:05:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:05:25 INFO - 'title': 't-yosemite-r7-0170', 03:05:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:05:25 INFO - 'use_talos_json': True, 03:05:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:05:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:05:25 INFO - 'work_dir': 'build'} 03:05:25 INFO - [mozharness: 2016-07-20 10:05:25.371315Z] Running clobber step. 03:05:25 INFO - Running pre-action listener: _resource_record_pre_action 03:05:25 INFO - Running main action method: clobber 03:05:25 INFO - rmtree: /builds/slave/test/build 03:05:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:05:26 INFO - Running post-action listener: _resource_record_post_action 03:05:26 INFO - [mozharness: 2016-07-20 10:05:26.433067Z] Finished clobber step (success) 03:05:26 INFO - [mozharness: 2016-07-20 10:05:26.433205Z] Running read-buildbot-config step. 03:05:26 INFO - Running pre-action listener: _resource_record_pre_action 03:05:26 INFO - Running main action method: read_buildbot_config 03:05:26 INFO - Using buildbot properties: 03:05:26 INFO - { 03:05:26 INFO - "project": "", 03:05:26 INFO - "product": "firefox", 03:05:26 INFO - "script_repo_revision": "production", 03:05:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 03:05:26 INFO - "repository": "", 03:05:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 03:05:26 INFO - "buildid": "20160720020121", 03:05:26 INFO - "pgo_build": "False", 03:05:26 INFO - "basedir": "/builds/slave/test", 03:05:26 INFO - "buildnumber": 301, 03:05:26 INFO - "slavename": "t-yosemite-r7-0170", 03:05:26 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:05:26 INFO - "platform": "yosemite_r7", 03:05:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:05:26 INFO - "branch": "mozilla-central", 03:05:26 INFO - "repo_path": "mozilla-central", 03:05:26 INFO - "revision": "3383b0da1a14340ec6096aca542eb73b0f7341d5", 03:05:26 INFO - "stage_platform": "macosx64", 03:05:26 INFO - "builduid": "1e3a1a8e47214f6dbfeed352286c3176", 03:05:26 INFO - "slavebuilddir": "test" 03:05:26 INFO - } 03:05:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:05:26 INFO - Running post-action listener: _resource_record_post_action 03:05:26 INFO - [mozharness: 2016-07-20 10:05:26.436006Z] Finished read-buildbot-config step (success) 03:05:26 INFO - [mozharness: 2016-07-20 10:05:26.436111Z] Running download-and-extract step. 03:05:26 INFO - Running pre-action listener: _resource_record_pre_action 03:05:26 INFO - Running main action method: download_and_extract 03:05:26 INFO - mkdir: /builds/slave/test/build/tests 03:05: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:05:26 INFO - https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:05:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:05:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:05:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:05:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 03:05:29 INFO - Downloaded 1199 bytes. 03:05:29 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:05:29 INFO - Using the following test package requirements: 03:05:29 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:05:29 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:05:29 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:05:29 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'jsshell-mac.zip'], 03:05:29 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:05:29 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:05:29 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:05:29 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:05:29 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:05:29 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:05:29 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:05:29 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:29 INFO - https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:05:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:05:31 INFO - Downloaded 18835937 bytes. 03:05:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:05:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:05:34 INFO - Return code: 0 03:05:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:34 INFO - https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:05:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:05:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:05:36 INFO - Downloaded 18835937 bytes. 03:05:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:05:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:05:40 INFO - Return code: 0 03:05:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:40 INFO - https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:05:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:05:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:05:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:05:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:05:41 INFO - Downloaded 14403317 bytes. 03:05:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:05:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:05:41 INFO - Return code: 0 03:05:41 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:05:41 INFO - https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:05:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:05:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:05:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:05:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:05:44 INFO - Downloaded 112923069 bytes. 03:05:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:05:44 INFO - mkdir: /builds/slave/test/properties 03:05:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:05:44 INFO - Writing to file /builds/slave/test/properties/build_url 03:05:44 INFO - Contents: 03:05:44 INFO - build_url:https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:05:45 INFO - Running post-action listener: _resource_record_post_action 03:05:45 INFO - Running post-action listener: set_extra_try_arguments 03:05:45 INFO - [mozharness: 2016-07-20 10:05:45.458959Z] Finished download-and-extract step (success) 03:05:45 INFO - [mozharness: 2016-07-20 10:05:45.459114Z] Running populate-webroot step. 03:05:45 INFO - Running pre-action listener: _resource_record_pre_action 03:05:45 INFO - Running main action method: populate_webroot 03:05:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:05:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:05:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:05:45 INFO - '--tppagecycles', 03:05:45 INFO - '7'], 03:05:45 INFO - 'tests': ['tsvgm']}}, 03:05:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:05:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:05:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:05:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:05:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['damp', 'tps']}, 03:05:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'tests': ['damp', 'tps']}, 03:05:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['dromaeo_dom']}, 03:05:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:05:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['basic_compositor_video']}, 03:05:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:05:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['a11yr', 03:05:45 INFO - 'ts_paint', 03:05:45 INFO - 'tpaint', 03:05:45 INFO - 'sessionrestore', 03:05:45 INFO - 'sessionrestore_no_auto_restore', 03:05:45 INFO - 'tabpaint']}, 03:05:45 INFO - 'other-e10s': {'tests': ['a11yr', 03:05:45 INFO - 'ts_paint', 03:05:45 INFO - 'tpaint', 03:05:45 INFO - 'sessionrestore', 03:05:45 INFO - 'sessionrestore_no_auto_restore', 03:05:45 INFO - 'tabpaint']}, 03:05:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:05:45 INFO - 'ts_paint', 03:05:45 INFO - 'tpaint', 03:05:45 INFO - 'sessionrestore', 03:05:45 INFO - 'sessionrestore_no_auto_restore', 03:05:45 INFO - 'tabpaint']}, 03:05:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:05:45 INFO - 'ts_paint', 03:05:45 INFO - 'tpaint', 03:05:45 INFO - 'sessionrestore', 03:05:45 INFO - 'sessionrestore_no_auto_restore', 03:05:45 INFO - 'tabpaint']}, 03:05:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['a11yr', 03:05:45 INFO - 'ts_paint', 03:05:45 INFO - 'tpaint', 03:05:45 INFO - 'sessionrestore', 03:05:45 INFO - 'sessionrestore_no_auto_restore', 03:05:45 INFO - 'tabpaint']}, 03:05:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['a11yr', 03:05:45 INFO - 'ts_paint', 03:05:45 INFO - 'tpaint', 03:05:45 INFO - 'sessionrestore', 03:05:45 INFO - 'sessionrestore_no_auto_restore', 03:05:45 INFO - 'tabpaint']}, 03:05:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['tsvgx', 03:05:45 INFO - 'tsvgr_opacity', 03:05:45 INFO - 'tart', 03:05:45 INFO - 'tscrollx', 03:05:45 INFO - 'cart']}, 03:05:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:05:45 INFO - 'tsvgr_opacity', 03:05:45 INFO - 'tart', 03:05:45 INFO - 'tscrollx', 03:05:45 INFO - 'cart']}, 03:05:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'talos_options': ['--disable-e10s'], 03:05:45 INFO - 'tests': ['tp5o']}, 03:05:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'tests': ['tp5o']}, 03:05:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'talos_options': ['--disable-e10s', 03:05:45 INFO - '--xperf_path', 03:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:45 INFO - 'tests': ['tp5n']}, 03:05:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:05:45 INFO - 'talos_options': ['--xperf_path', 03:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:45 INFO - 'tests': ['tp5n']}}, 03:05:45 INFO - 'talos.zip': {'path': '', 03:05:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:05:45 INFO - Running post-action listener: _resource_record_post_action 03:05:45 INFO - [mozharness: 2016-07-20 10:05:45.477982Z] Finished populate-webroot step (success) 03:05:45 INFO - [mozharness: 2016-07-20 10:05:45.478122Z] Running create-virtualenv step. 03:05:45 INFO - Running pre-action listener: _resource_record_pre_action 03:05:45 INFO - Running main action method: create_virtualenv 03:05:45 INFO - Creating virtualenv /builds/slave/test/build/venv 03:05:45 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:05:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:05:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:05:45 INFO - Using real prefix '/tools/python27' 03:05:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:05:46 INFO - Installing distribute.............................................................................................................................................................................................done. 03:05:49 INFO - Installing pip.................done. 03:05:49 INFO - Return code: 0 03:05:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:05: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:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05: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:05: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:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:49 INFO - 'HOME': '/Users/cltbld', 03:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:49 INFO - 'LOGNAME': 'cltbld', 03:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:49 INFO - 'MOZ_NO_REMOTE': '1', 03:05:49 INFO - 'NO_EM_RESTART': '1', 03:05:49 INFO - 'PAGER': '/bin/cat', 03:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:49 INFO - 'PWD': '/builds/slave/test', 03:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:49 INFO - 'SHELL': '/bin/bash', 03:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:05:49 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:49 INFO - 'USER': 'cltbld', 03:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:49 INFO - 'XPC_FLAGS': '0x0', 03:05:49 INFO - 'XPC_SERVICE_NAME': '0', 03:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:49 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:05:49 INFO - Cleaning up... 03:05:49 INFO - Return code: 0 03:05:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:05: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:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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', '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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:05: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 psutil>=3.1.1 03:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:49 INFO - 'HOME': '/Users/cltbld', 03:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:49 INFO - 'LOGNAME': 'cltbld', 03:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:49 INFO - 'MOZ_NO_REMOTE': '1', 03:05:49 INFO - 'NO_EM_RESTART': '1', 03:05:49 INFO - 'PAGER': '/bin/cat', 03:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:49 INFO - 'PWD': '/builds/slave/test', 03:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:49 INFO - 'SHELL': '/bin/bash', 03:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:05:49 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:49 INFO - 'USER': 'cltbld', 03:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:49 INFO - 'XPC_FLAGS': '0x0', 03:05:49 INFO - 'XPC_SERVICE_NAME': '0', 03:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:49 INFO - Downloading/unpacking psutil>=3.1.1 03:05:49 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:05:49 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:05:49 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:05:49 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:05:49 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:05:49 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:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:05:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:52 INFO - Installing collected packages: psutil 03:05:52 INFO - Running setup.py install for psutil 03:05:52 INFO - building 'psutil._psutil_osx' extension 03:05:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:05:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:05:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:05:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:05:53 INFO - building 'psutil._psutil_posix' extension 03:05:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:05:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:05:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:05:53 INFO - ^ 03:05:53 INFO - 1 warning generated. 03:05:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:05:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:53 INFO - Successfully installed psutil 03:05:53 INFO - Cleaning up... 03:05:53 INFO - Return code: 0 03:05:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:53 INFO - 'HOME': '/Users/cltbld', 03:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:53 INFO - 'LOGNAME': 'cltbld', 03:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:53 INFO - 'MOZ_NO_REMOTE': '1', 03:05:53 INFO - 'NO_EM_RESTART': '1', 03:05:53 INFO - 'PAGER': '/bin/cat', 03:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:53 INFO - 'PWD': '/builds/slave/test', 03:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:53 INFO - 'SHELL': '/bin/bash', 03:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:05:53 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:53 INFO - 'USER': 'cltbld', 03:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:53 INFO - 'XPC_FLAGS': '0x0', 03:05:53 INFO - 'XPC_SERVICE_NAME': '0', 03:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:05: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:05: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:05: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:05: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:05: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:05: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:05:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:05:56 INFO - Installing collected packages: mozsystemmonitor 03:05:56 INFO - Running setup.py install for mozsystemmonitor 03:05:56 INFO - Successfully installed mozsystemmonitor 03:05:56 INFO - Cleaning up... 03:05:56 INFO - Return code: 0 03:05:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:56 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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:05:56 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:05:56 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:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:56 INFO - 'HOME': '/Users/cltbld', 03:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:56 INFO - 'LOGNAME': 'cltbld', 03:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:56 INFO - 'MOZ_NO_REMOTE': '1', 03:05:56 INFO - 'NO_EM_RESTART': '1', 03:05:56 INFO - 'PAGER': '/bin/cat', 03:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:56 INFO - 'PWD': '/builds/slave/test', 03:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:56 INFO - 'SHELL': '/bin/bash', 03:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:05:56 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:56 INFO - 'USER': 'cltbld', 03:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:56 INFO - 'XPC_FLAGS': '0x0', 03:05:56 INFO - 'XPC_SERVICE_NAME': '0', 03:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:56 INFO - Downloading/unpacking jsonschema==2.5.1 03:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:05:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:05:59 INFO - warning: no files found matching '*.txt' 03:05:59 INFO - no previously-included directories found matching 'build' 03:05:59 INFO - no previously-included directories found matching 'dist' 03:05:59 INFO - no previously-included directories found matching '.git*' 03:05:59 INFO - Installing collected packages: jsonschema, functools32 03:05:59 INFO - Running setup.py install for functools32 03:05:59 INFO - warning: no files found matching '*.txt' 03:05:59 INFO - no previously-included directories found matching 'build' 03:05:59 INFO - no previously-included directories found matching 'dist' 03:05:59 INFO - no previously-included directories found matching '.git*' 03:06:00 INFO - Successfully installed jsonschema functools32 03:06:00 INFO - Cleaning up... 03:06:00 INFO - Return code: 0 03:06:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:00 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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:00 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:06:00 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:06:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:06:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:00 INFO - 'HOME': '/Users/cltbld', 03:06:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:00 INFO - 'LOGNAME': 'cltbld', 03:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:00 INFO - 'MOZ_NO_REMOTE': '1', 03:06:00 INFO - 'NO_EM_RESTART': '1', 03:06:00 INFO - 'PAGER': '/bin/cat', 03:06:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:00 INFO - 'PWD': '/builds/slave/test', 03:06:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:00 INFO - 'SHELL': '/bin/bash', 03:06:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:06:00 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:06:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:00 INFO - 'USER': 'cltbld', 03:06:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:00 INFO - 'XPC_FLAGS': '0x0', 03:06:00 INFO - 'XPC_SERVICE_NAME': '0', 03:06:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:06: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:06: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:06: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:06: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:06: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:06: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:06:02 INFO - Downloading blobuploader-1.2.4.tar.gz 03:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:06:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:06:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:06:03 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:06:03 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:06:03 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:06:03 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:06:03 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:06:03 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:06:04 INFO - Downloading docopt-0.6.1.tar.gz 03:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:06:04 INFO - Installing collected packages: blobuploader, requests, docopt 03:06:04 INFO - Running setup.py install for blobuploader 03:06:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:06:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:06:04 INFO - Running setup.py install for requests 03:06:04 INFO - Running setup.py install for docopt 03:06:04 INFO - Successfully installed blobuploader requests docopt 03:06:04 INFO - Cleaning up... 03:06:05 INFO - Return code: 0 03:06:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:06:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:05 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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:05 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:06:05 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:06:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:06:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:05 INFO - 'HOME': '/Users/cltbld', 03:06:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:05 INFO - 'LOGNAME': 'cltbld', 03:06:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:05 INFO - 'MOZ_NO_REMOTE': '1', 03:06:05 INFO - 'NO_EM_RESTART': '1', 03:06:05 INFO - 'PAGER': '/bin/cat', 03:06:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:05 INFO - 'PWD': '/builds/slave/test', 03:06:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:05 INFO - 'SHELL': '/bin/bash', 03:06:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:06:05 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:06:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:05 INFO - 'USER': 'cltbld', 03:06:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:05 INFO - 'XPC_FLAGS': '0x0', 03:06:05 INFO - 'XPC_SERVICE_NAME': '0', 03:06:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Ydi8GO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Bq13XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tAXAAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-A0uVZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-rxyYLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-IsaOBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VKS8Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:06:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:06:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-yypfI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-g_BaCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-UBOPja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ysdUBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-rDQK2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ylUtl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iyqGTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-uxrP2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-to_jOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:06:06 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iCdbYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:06:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:06:07 INFO - Running setup.py install for manifestparser 03:06:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:06:07 INFO - Running setup.py install for mozcrash 03:06:07 INFO - Running setup.py install for mozdebug 03:06:07 INFO - Running setup.py install for mozdevice 03:06:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:06:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:06:07 INFO - Running setup.py install for mozfile 03:06:07 INFO - Running setup.py install for mozhttpd 03:06:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:06:07 INFO - Running setup.py install for mozinfo 03:06:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Running setup.py install for mozInstall 03:06:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Running setup.py install for mozleak 03:06:08 INFO - Running setup.py install for mozlog 03:06:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Running setup.py install for moznetwork 03:06:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Running setup.py install for mozprocess 03:06:08 INFO - Running setup.py install for mozprofile 03:06:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:06:08 INFO - Running setup.py install for mozrunner 03:06:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:06:09 INFO - Running setup.py install for mozscreenshot 03:06:09 INFO - Running setup.py install for moztest 03:06:09 INFO - Running setup.py install for mozversion 03:06:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:06:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:06:09 INFO - Cleaning up... 03:06:09 INFO - Return code: 0 03:06:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06: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:06:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06: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:06:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:09 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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:09 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:06:09 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:06:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:06:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:09 INFO - 'HOME': '/Users/cltbld', 03:06:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:09 INFO - 'LOGNAME': 'cltbld', 03:06:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:09 INFO - 'MOZ_NO_REMOTE': '1', 03:06:09 INFO - 'NO_EM_RESTART': '1', 03:06:09 INFO - 'PAGER': '/bin/cat', 03:06:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:09 INFO - 'PWD': '/builds/slave/test', 03:06:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:09 INFO - 'SHELL': '/bin/bash', 03:06:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:06:09 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:06:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:09 INFO - 'USER': 'cltbld', 03:06:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:09 INFO - 'XPC_FLAGS': '0x0', 03:06:09 INFO - 'XPC_SERVICE_NAME': '0', 03:06:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:06:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-k8tkUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:06:09 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:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:06:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-fF5qEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ooveXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-TB7E6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VRgdqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-BxR8aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-xzyDWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-0mrtvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-nJA2VB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-hlGcQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:06:10 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:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:06:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ObqXM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:06:11 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:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:06:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-IYiOaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:06:11 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:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:06:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-TYVwss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:06:11 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:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:06:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-yNFx6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:06:11 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:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:06:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VJiUU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:06:11 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:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:06:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-rc6ht5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:06:11 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:06:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:06:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QSOR7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:06:11 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:06:11 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:06:11 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:06:11 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:06:11 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:06:11 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:06:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:06:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:06:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:06:14 INFO - Downloading blessings-1.6.tar.gz 03:06:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:06:14 INFO - Installing collected packages: blessings 03:06:14 INFO - Running setup.py install for blessings 03:06:14 INFO - Successfully installed blessings 03:06:14 INFO - Cleaning up... 03:06:14 INFO - Return code: 0 03:06:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:06:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:06:14 INFO - Reading from file tmpfile_stdout 03:06:14 INFO - Current package versions: 03:06:14 INFO - blessings == 1.6 03:06:14 INFO - blobuploader == 1.2.4 03:06:14 INFO - docopt == 0.6.1 03:06:14 INFO - functools32 == 3.2.3-2 03:06:14 INFO - jsonschema == 2.5.1 03:06:14 INFO - manifestparser == 1.1 03:06:14 INFO - mozInstall == 1.12 03:06:14 INFO - mozcrash == 0.17 03:06:14 INFO - mozdebug == 0.1 03:06:14 INFO - mozdevice == 0.48 03:06:14 INFO - mozfile == 1.2 03:06:14 INFO - mozhttpd == 0.7 03:06:14 INFO - mozinfo == 0.9 03:06:14 INFO - mozleak == 0.1 03:06:14 INFO - mozlog == 3.3 03:06:14 INFO - moznetwork == 0.27 03:06:14 INFO - mozprocess == 0.23 03:06:14 INFO - mozprofile == 0.28 03:06:14 INFO - mozrunner == 6.12 03:06:14 INFO - mozscreenshot == 0.1 03:06:14 INFO - mozsystemmonitor == 0.3 03:06:14 INFO - moztest == 0.7 03:06:14 INFO - mozversion == 1.4 03:06:14 INFO - psutil == 3.1.1 03:06:14 INFO - requests == 1.2.3 03:06:14 INFO - wsgiref == 0.1.2 03:06:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:06:14 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:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:14 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:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:14 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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:14 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:06:14 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:06:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:06:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:14 INFO - 'HOME': '/Users/cltbld', 03:06:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:14 INFO - 'LOGNAME': 'cltbld', 03:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:14 INFO - 'MOZ_NO_REMOTE': '1', 03:06:14 INFO - 'NO_EM_RESTART': '1', 03:06:14 INFO - 'PAGER': '/bin/cat', 03:06:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:14 INFO - 'PWD': '/builds/slave/test', 03:06:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:14 INFO - 'SHELL': '/bin/bash', 03:06:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:06:14 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:06:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:14 INFO - 'USER': 'cltbld', 03:06:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:14 INFO - 'XPC_FLAGS': '0x0', 03:06:14 INFO - 'XPC_SERVICE_NAME': '0', 03:06:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 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:06:15 INFO - Cleaning up... 03:06:15 INFO - Return code: 0 03:06:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:06: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:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06: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:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:06:15 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 0x1057c7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10585c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96a359aaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:06:15 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:06:15 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:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:15 INFO - 'HOME': '/Users/cltbld', 03:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:15 INFO - 'LOGNAME': 'cltbld', 03:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:15 INFO - 'MOZ_NO_REMOTE': '1', 03:06:15 INFO - 'NO_EM_RESTART': '1', 03:06:15 INFO - 'PAGER': '/bin/cat', 03:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:15 INFO - 'PWD': '/builds/slave/test', 03:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:15 INFO - 'SHELL': '/bin/bash', 03:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:06:15 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:15 INFO - 'USER': 'cltbld', 03:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:15 INFO - 'XPC_FLAGS': '0x0', 03:06:15 INFO - 'XPC_SERVICE_NAME': '0', 03:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:06:15 INFO - Cleaning up... 03:06:15 INFO - Return code: 0 03:06:15 INFO - Running post-action listener: _resource_record_post_action 03:06:15 INFO - Running post-action listener: _start_resource_monitoring 03:06:15 INFO - Starting resource monitoring. 03:06:15 INFO - [mozharness: 2016-07-20 10:06:15.354496Z] Finished create-virtualenv step (success) 03:06:15 INFO - [mozharness: 2016-07-20 10:06:15.355576Z] Running install step. 03:06:15 INFO - Running pre-action listener: _resource_record_pre_action 03:06:15 INFO - Running main action method: install 03:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:06:15 INFO - Reading from file tmpfile_stdout 03:06:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:06:15 INFO - Reading from file tmpfile_stdout 03:06:15 INFO - Output received: 03:06:15 INFO - Usage: mozinstall [options] installer 03:06:15 INFO - Options: 03:06:15 INFO - -h, --help show this help message and exit 03:06:15 INFO - -d DEST, --destination=DEST 03:06:15 INFO - Directory to install application into. [default: 03:06:15 INFO - "/builds/slave/test"] 03:06:15 INFO - --app=APP Application being installed. [default: firefox] 03:06:15 INFO - mkdir: /builds/slave/test/build/application 03:06:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:06:49 INFO - Reading from file tmpfile_stdout 03:06:49 INFO - Output received: 03:06:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:06:49 INFO - Running post-action listener: _resource_record_post_action 03:06:49 INFO - [mozharness: 2016-07-20 10:06:49.902787Z] Finished install step (success) 03:06:49 INFO - [mozharness: 2016-07-20 10:06:49.902912Z] Running run-tests step. 03:06:49 INFO - Running pre-action listener: _resource_record_pre_action 03:06:49 INFO - Running main action method: run_tests 03:06:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:06:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:06:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:06:49 INFO - 2016-07-20 03:06:49.917 screenresolution[2543:23120] starting screenresolution argv=screenresolution get 03:06:49 INFO - 2016-07-20 03:06:49.920 screenresolution[2543:23120] Display 0: 1600x1200x32@60 03:06:49 INFO - 2016-07-20 03:06:49.926 screenresolution[2544:23123] starting screenresolution argv=screenresolution list 03:06:49 INFO - Available Modes on Display 0 03:06:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:06:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:06:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:06:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:06:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:06:50 INFO - Intel Iris: 03:06:50 INFO - Chipset Model: Intel Iris 03:06:50 INFO - Type: GPU 03:06:50 INFO - Bus: Built-In 03:06:50 INFO - VRAM (Dynamic, Max): 1536 MB 03:06:50 INFO - Vendor: Intel (0x8086) 03:06:50 INFO - Device ID: 0x0a2e 03:06:50 INFO - Revision ID: 0x0009 03:06:50 INFO - Displays: 03:06:50 INFO - Display: 03:06:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:06:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:06:50 INFO - Display Serial Number: 1600x1200 60 03:06:50 INFO - Main Display: Yes 03:06:50 INFO - Mirror: Off 03:06:50 INFO - Online: Yes 03:06:50 INFO - Rotation: Supported 03:06:50 INFO - Return code: 0 03:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:06:50 INFO - Python 2.7.3 03:06:50 INFO - Return code: 0 03:06:50 INFO - grabbing minidump binary from tooltool 03:06: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:06:50 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/macosx64/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 0x10572bd30>, '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 0x105490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105723b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:06:50 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:06:50 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:06:50 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:06:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:06:50 INFO - Return code: 0 03:06:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:06:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:06:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:06:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:06:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:06:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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:06:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --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:06:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QY2VLXHYOv/Render', 03:06:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:06:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:06:50 INFO - 'HOME': '/Users/cltbld', 03:06:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:06:50 INFO - 'LOGNAME': 'cltbld', 03:06:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:06:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:06:50 INFO - 'MOZ_NO_REMOTE': '1', 03:06:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:06:50 INFO - 'NO_EM_RESTART': '1', 03:06:50 INFO - 'PAGER': '/bin/cat', 03:06:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:06:50 INFO - 'PWD': '/builds/slave/test', 03:06:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:06:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:06:50 INFO - 'SHELL': '/bin/bash', 03:06:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners', 03:06:50 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 03:06:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:06:50 INFO - 'USER': 'cltbld', 03:06:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:06:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:06:50 INFO - 'XPC_FLAGS': '0x0', 03:06:50 INFO - 'XPC_SERVICE_NAME': '0', 03:06:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:06:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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:06:50 INFO - mozversion application_buildid: 20160720020121 03:06:50 INFO - mozversion application_changeset: 3383b0da1a14340ec6096aca542eb73b0f7341d5 03:06:50 INFO - mozversion application_display_name: Nightly 03:06:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:06:50 INFO - mozversion application_name: Firefox 03:06:50 INFO - mozversion application_remotingname: firefox 03:06:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:06:50 INFO - mozversion application_vendor: Mozilla 03:06:50 INFO - mozversion application_version: 50.0a1 03:06:50 INFO - mozversion platform_buildid: 20160720020121 03:06:50 INFO - mozversion platform_changeset: 3383b0da1a14340ec6096aca542eb73b0f7341d5 03:06:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:06:50 INFO - mozversion platform_version: 50.0a1 03:06:50 INFO - using testdate: 1469009210 03:06:50 INFO - actual date: 1469009210 03:06:50 INFO - starting webserver on 'localhost:55006' 03:06:50 INFO - SUITE-START | Running 2 tests 03:06:50 INFO - TEST-START | dromaeo_css 03:06:50 INFO - operating with platform_type : mac_ 03:06:50 INFO - Initialising browser for dromaeo_css test... 03:06:50 INFO - TEST-INFO | started process 2554 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpBAFWJ8/profile http://localhost:55006/getInfo.html) 03:06:52 INFO - PROCESS | 2554 | __metrics Screen width/height:1600/1200 03:06:52 INFO - PROCESS | 2554 | colorDepth:24 03:06:52 INFO - PROCESS | 2554 | Browser inner width/height: 1024/689 03:06:52 INFO - PROCESS | 2554 | __metrics 03:06:52 INFO - PROCESS | 2554 | 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:06:52 INFO - PROCESS | 2554 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:06:53 INFO - TEST-INFO | 2554: exit 0 03:06:53 INFO - Browser initialized. 03:06:53 INFO - Running cycle 1/1 for dromaeo_css test... 03:06:53 INFO - TEST-INFO | started process 2556 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpBAFWJ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:05 INFO - PROCESS | 2556 | RSS: Main: 245288960 03:07:05 INFO - PROCESS | 2556 | 03:07:57 INFO - PROCESS | 2556 | 1469009277488 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:10:07 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:55006/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55006/tests/dromaeo/cssquery-ext.html) 03:10:09 INFO - PROCESS | 2556 | RSS: Main: 397119488 03:10:09 INFO - PROCESS | 2556 | 03:13:12 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:55006/tests/dromaeo/cssquery-ext.html (next: http://localhost:55006/tests/dromaeo/cssquery-jquery.html) 03:13:13 INFO - PROCESS | 2556 | RSS: Main: 381669376 03:13:13 INFO - PROCESS | 2556 | 03:16:15 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:55006/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55006/tests/dromaeo/cssquery-mootools.html) 03:16:15 INFO - PROCESS | 2556 | RSS: Main: 370917376 03:16:15 INFO - PROCESS | 2556 | 03:19:18 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:55006/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55006/tests/dromaeo/cssquery-prototype.html) 03:19:18 INFO - PROCESS | 2556 | RSS: Main: 371687424 03:19:18 INFO - PROCESS | 2556 | 03:22:22 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:55006/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55006/tests/dromaeo/cssquery-yui.html) 03:22:23 INFO - PROCESS | 2556 | RSS: Main: 344141824 03:22:23 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:55006/tests/dromaeo/cssquery-yui.html (next: http://localhost:55006/tests/dromaeo/cssquery-dojo.html) 03:25:26 INFO - PROCESS | 2556 | RSS: Main: 382943232 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | __start_tp_report 03:25:26 INFO - PROCESS | 2556 | _x_x_mozilla_page_load 03:25:26 INFO - PROCESS | 2556 | _x_x_mozilla_page_load_details 03:25:26 INFO - PROCESS | 2556 | |i|pagename|runs| 03:25:26 INFO - PROCESS | 2556 | |0;dojo.html;3315.088350703557;3325.0523600773786;3329.8002119872795;3350.4806754952983;3397.728181745459;5530.889382212354;5580.079286917654;5598.888022239553;5655.434456554348;5702.372738998707;5602.60781745278;5674.574406919477;5727.799527016556;5735.684537350444;5735.741891614877;5105.668131571455;5108.438071812097;5169.457206993268;5195.532402083812;5253.763580638862;5232.659877107976;5274.287971123907;5277.445868183843;5285.075111855429;5306.70813105279;5109.412417571971;5153.3815942087185;5166.509181627739;5193.298904647881;5210.557102646279;5131.358580177478;5136.563392111675;5149.6910185389;5202.3497062867145;5210.817621383233;5109.744512774347;5117.334746482933;5137.871553211162;5185.662931909414;5254.343207099113;5572.0527510323345;5674.375818659944;5763.596548241625;5772.509336706385;5779.017567013576;6096.683260361079;6151.231096112986;6163.691815409212;6199.938000619981;6211.9068213976825;6100.572959892809;6235.781747638856;6277.780277690259;6306.905396419057;6312.147860038882;5581.49766521015;5624.959382514248;5790.189373487715;5799.246098007275;5803.21656576361;6315.431611155018;6360.459360954278;6365.554411191265;6366.904496432558;6380.170577824853;6073.681704558921;6077.118817771398;6092.555372131625;6095.994160963392;6096.3903609639565;6104.084387341934;6134.631922084689;6248.125262463259;6250.343713910065;6266.373362663789;6086.147939288503;6170.259568851767;6184.443400094013;6188.628682279078;6192.225971753531;5147.459516750687;6168.537359697951;6212.316645169028;6232.096346029891;6236.937630623726;4773.71357718538;4840.249761287007;4857.9028419431415;4887.486813884562;4892.339534162842;4422.977885110554;4441.089576636782;4464.218761716752;4464.977675111669;4472.217361961643;5584.5811564132855;5633.211350410946;5634.718264086861;5643.1535269709875;5672.716364181857;6213.937860621426;6216.533759968021;6218.440340369389;6235.314115447297;6247.75008999644;5605.383407825136;5644.463775941279;5654.180143879114;5672;5696.174054762037;5240.65935714185;5311.920321195185;5313.07021271276;5365.222042803772;5366.543843773245;5890.4698577128265;6357.682115894279;6359.395857393541;6372.745090196433;6380.776672816429;6185.474234690102;6209.130721699056;6238.344973777688;6248.343923888108;6257.68711564429;5299.311089558333;6241.563090583615;6304.621722696653;6352.809415717536;6354.237491500865;6130.295016073031;6148.44663980244;6166.537509686702;6166.722497487534;6222.564420490522;6056.333803281547;6205;6206.100115483139;6225.906611400742;6233.906491402723;5812.854678633068;5895.587308888336;5900.616459930091;5926.2295901532525;5969.880602388015;5906.40935906406;5931.525477961839;5932.347441781487;5943;5949.940500594938;5836.620619659709;5869.031609784509;5883.0293001654245;5890.2342695449315;5905.202797622266;5952.761889524544;5995.220621319375;6002.159697642246;6012.939870601238;6027.8191654248685;5692.7722891083895;5752.395998420106;5764;5840.503557197516;5843.152742852178 03:25:26 INFO - PROCESS | 2556 | |1;ext.html;500.0823488897872;515.319777893181;519.0242344392542;524.5881982643625;527.3855957809153;29709;30280.697193028063;40960.38559421604;42427.78786106065;42585.95433433819;43346.13307733843;43544.69365919028;43670;43782.12435751283;43940.340894886605;9731.659391921301;9888.505574721272;9889.307748457612;9896.752581185492;9900.108990190882;9703.95148024259;9822.968588298232;9839.163671087967;9854.162396196332;9869.358491698016;9709.514524273793;9837.311388202794;9866.753331166743;9871.259655525864;9880.25898057645;9790.951045244763;9831.705048848546;9848;9853.605855765762;9870.2103831694;9741;9807.509624518816;9809.558569864374;9828.852567211427;9837.508124593742;41441.1711765763;41636.58363416357;41725.37411938823;41745.582544174475;41823.163536729095;3681.907952301187;3915.647591716759;4029.657479114279;4213.683973701954;4352.36890650857;31671.20821979446;32085.358292834248;32220.516692249636;32803.6719632803;32851.6714832851;3234.110619579611;3248.642649308574;3267.640559538449;3371.844159961958;3379.797212167278;523738;526714;527070;527469;527902;58423.41576584265;58883.411165887796;58930.52673683107;58945.705271473365;59043.40956590379;34617.48073778845;35093.82453087718;35325;36561.81719091388;36658.45012324817;16487.093209873332;16545.25546350393;16711.83288167127;16730.74903876443;16738.497845064667;12751.48994040228;12839.743205136032;12858.292793896238;12858.807117893051;12919.095663303478;19399.321023764383;19444;19563.197490283772;19590.118444670024;19606;8024.518528888286;8164;8173.038470766973;8175.223353781381;8230.711925082713;872;907.847034266491;952.8475443928938;962.552413127899;1105.6019832860163;30178;30310.84844575763;30336.69663303383;30775.23061923425;31071.533926991113;756.9583672898043;1036.7719101797593;1086.1256688365784;1098.7143342730947;1100.6532942123206;15153.848461515465;15640.139792311305;15770.60573485649;15922.044677319398;16228.59428514273;711532;719885;722224;723447;727591;59612.40387596155;60031;60128.69935650294;60148.69925650344;60343;1270.6251655761573;1401.1663060478795;1401.7897315402772;1432.0548438031058;1433.118632041308;26915.730842691322;31252.843735781174;31455.056348309587;31597.21006974752;31689.36621267608;40936.59063409328;50088.74955625198;50112.74943625258;50188.498115019844;50222;531047;546316;550590;550666;550778;23441.41396465102;23965.16121935724;26063.08779192719;26320.8683956579;26559.468810624065;48629.27056094232;48942.51057489379;49731;49961.25290603292;50176.749116254185;38998;40563;41360.1727965433;42310.78844605757;42770.78614606907;40096.79951600223;40856.978575535846;41037.38443923284;41687.791561042;41775.1644967105;34241.828790855885;37586.624133759404;38439.423408649454;38785.80607096946;39030.2193956125;33423.49864752079;35209.64790352166;35215.11962200965;35310.646893530735;36307.092322692144;25382.111626093;25437.236882893543;25547.36131596725;26037.609435858856;26325 03:25:26 INFO - PROCESS | 2556 | |2;jquery.html;361.22877656203997;382.17259632894786;389.69993105308913;396.08109186686863;411.9382092686097;15703.293351799168;16958.49124526263;20122.798772012276;21275.787242127575;21341.893290533524;20588.89705551474;20868.686969695467;20919.267825626106;20937.685934710993;20962;5755.597108202427;5863.736131874076;6031.457168854802;6139.87720245597;6141.815745527619;5933.732982015819;5957.434043765857;6009.729562169691;6024.48791852693;6028.095785632168;5076.18780995039;5757.827265182031;5961.374055724152;5961.970190149043;6085.208922840025;6066.181065556138;6081.695915204266;6082.9391706082815;6083.513318934497;6102.481289090432;5033.974830125826;5338.946610533883;5341.732913354317;5362.570994320445;5385.380681221671;12625.873741262561;12669.74660506794;12796.360181990956;12939.48242070316;12990.480380784757;2152.720146380976;2200.7579166291694;2285.931422057324;2370.9644355334513;2465.6671349367784;9987.101160895554;10168.576399304104;10281.485925703684;10370.9481452593;13727.176369417866;1742.573069597944;1890.754201953751;1905.6474552207746;1934;1996.151635554898;103623;113539;122103.38948305379;124667.3766631161;134875.88430867626;9721.513924303898;11107.000369966741;11193.384363859903;11242.831357529643;11470.54117835294;14198.574042778733;14288.785668214778;14326.140431574138;14326.355314010894;14338.56984290473;8395.496270223684;8588.355873309527;8628.180322869317;8689.522076285739;8723.171298726611;6856.314368563104;8363.414560980673;8568.10034946334;8691;8739.344549158839;3828.559715632738;4533.184026875128;4537.523560026216;4541.5231400703115;4602.7008244464005;1873.3162395725462;1913.86602937793;1914.15777058094;1923.9038048097539;1928.7878333383317;873.2446433834688;962.6630679262203;963.4460185393286;977.6773664690635;988.1304452082122;16592.585185370463;21261.46846328823;21263.574728505344;21398;21398.893005535185;795.7572940253278;942.707760594218;957.1337939165066;981.7005813227034;1072.2601405030505;10683.14534837211;10878.377705401397;11040.613578524873;11062.115030797517;11146.996770290712;557044;566418;571879;576900;598694;74268.62865685746;74540;108274;119592.40203798925;136821;833.3250067445389;933.2114363362947;940.8071345374182;972.7422233108232;975.6048800235774;14739.55781326562;18701;18713.567236259198;18721.438356849318;18799.36887477553;55004.174937376774;55537.444625553224;55797;55846;56499;83451.5827420859;84135.15864841273;84865;85018;85159;6612.5040621952585;7870.606469676608;7927.326177274997;7988.400869934662;8204.425690201628;12242.449089790802;13076.215427074407;13353.265570393722;13499.39252733649;13654.86345136536;11046.944765276123;11341.072017756836;11451.599194028502;11525.827112593486;11689.707757306136;11372.488238029144;11426.371549564854;11505.137114716263;11543.249688770204;11563.710907227387;11667.883321166679;11739.647810565355;11778.411079445823;11935.820962685393;12002.21985570935;8080.83838323242;8128.227818357246;8217.137200593852;8259.339252859636;8473.322134229371;9797.265205109503;11436.599719009795;11525.020373268366;11677.357745323749;11744.58893938708 03:25:26 INFO - PROCESS | 2556 | |3;mootools.html;1548.07889305863;1599.6000999750063;1784.7769028871392;1877.7371168036468;1915.1956178405078;7867.134615192324;8081.595920203981;8532.488050716953;9006.414583052105;9020.188183063532;7985;8070.677172913076;8430.283425908805;8662.177093176138;8671.262942649866;2346.894389752457;2354.72920614129;2388.0686532252435;2389.3429306940643;2395.3652282145244;2086.8643538170045;2355.646653002055;2371.3123194273726;2397.520495900818;2402.087206861399;1950.2199120351831;2132.0832042221837;2284.4288927768057;2340.0873659272897;2354.682117914085;2324.2097686786574;2342.4612339161918;2346.765323467657;2347.119830063726;2349.4243910242017;2324.0819876148826;2357.281029286067;2358.4929240213264;2361.681173041635;2362.2795047510504;8959;9022.909770902272;9031.774205644911;9040.728778136667;9074.319426043006;5243.475652434726;5407.107827208506;5424.105022671294;5424.294841670597;5429.837104886859;3465.42820434628;3545.1468555033007;3628.709703223762;3643.7631553949172;3654.543182102237;1066.4134725900724;1079.0073132717919;1089.54239219528;1099.8790133085354;1102.7518808268155;9761.755956101084;11844.170908036442;11848;11860;11882.28706277626;3699.3341198583976;4608.124456353282;4786.736729479842;4825.1555977703765;4826.444958829779;10001.949990250003;10006.49967501622;10009.049140331657;10015.048570385801;10023.899761002442;5321.600879934021;5322.0952438085615;5324.680519168863;5325.227841962894;5332.2801421808335;4521.321801729701;4738.265568836767;4858.222684370484;4861.6353773467135;4865.172920603506;3334.549835772188;3667.8716244931297;3921.490206273223;3957.2679054375035;4054.8986275343354;1104.9116070714324;1207.2635692227734;1212.8120141377924;1231.5381731850557;1258.1758947889148;733.2557454184008;880.3837313880182;945.3760518058186;956.2588993529931;959.2230293462318;6039.969800151059;6157.337518869969;6178;6181.289151747516;6198.845028874314;905.7871400220943;979.1432496565506;1017.9898201018043;1038.8832005594018;1067.658349328223;4067.633912947849;4072.6131017553284;4109.712320137622;4199.664026877843;4205.852795152155;10446.843297350546;10754.731131721614;10869.619563315404;10876.836847447295;10902.182336324808;9034.2320902724;10780.029797318122;10798.51406686701;10803.729906752395;10806.459677016117;7333.01004364404;7949.7217597383815;8279.793005174919;8299.045609754834;8424.831503369898;6093.634381937098;6103.23709536308;6162.815115546541;6259.7167580646765;6335.113084168128;9969.70393848795;10142.746431339276;10274.589016439259;10288.537015834156;10293.43386113771;10409.375437473776;10415.010573995458;10433.26967112295;10434.895651043393;10456.738581535523;7067.752628657972;7159.319864612853;7208.675609597476;7226.783196504113;7247.094113235846;8389.203025712548;8390.496570205807;8493.91506084956;8514.957425212835;8516.40385173032;7379.520331178671;7535.962320188364;7609.39124869998;7740.690372385042;7843.882341764992;7260.636968151677;7461.253874612496;7518.060242469692;7565;7678.308952194329;6006.909896351471;7064.434845212268;7069.82325441868;7125.1093613298335;7129.928700712927;4942.851714448572;5106.974465127799;5116.181410974226;5171.63798534099;5238.842834714965;5123.180291153398;5132.7433628317685;5138.100832354397;5159.277701121921;5327.467253274796 03:25:26 INFO - PROCESS | 2556 | |4;prototype.html;306.4040441341591;310.72811290121143;315.2119700748129;320.26978489044984;323.1162769824531;4350.825966961322;4441.5336389679105;5269.1832765921245;5314.548263397611;5316.20256961456;4581.266997280436;5318.553465405908;5342.786288548463;5358.946410535894;5380.515753582177;3480.7215422766244;3806.7525610835346;4016.5380981187245;4019.9799001004953;4085.22380747657;3510.508528805969;3914.965901426389;3936.822842972059;4065.780265920227;4095.9180816383655;3322.9833850830714;3993.2013597280516;4046.5751096134777;4050.116695736482;4066.654334381581;3027.303720144357;3577.5672542670973;4080.245154646385;4104.856330028435;4109.91780164398;3345.2974875276223;3420.521127042216;3460.8615655373756;3535.5934067582043;3549.343371476285;5130.461301563357;5145.434002259748;5146.922796158061;5149.536541711226;5158.561522270612;4618.861434156981;4624.791884365211;4626.4910859805395;4642.117997580483;4644.978104816935;5295.7881684732565;5526.778928842841;5531.3915469298345;5537.895599672099;5566.860828479321;3242.221866751986;3879.72841901067;3880.2239552089695;3912.5304963404287;3915.334393153171;4867.495403309611;5329.173978033411;5537.529310008654;5685.545156387479;5844.603712477864;3333.3999880021343;3344.464885618338;3473.7394695397443;3926.2343842950922;3947.6447119758786;3463.4631632097066;3988.880333589997;4018.5378681452007;4036.788963310995;4062.471878655756;4306.849260275875;4643.814247430065;4665.9766701166955;4671.719696818202;4688.906221875612;5604.215409842625;5636.915446268309;5646.470955652717;5662.348829884533;5694.572907031907;1322.2066759944148;1343.5230493174977;1350.1831392007891;1360.4490181476517;1366.2417358366004;805.5569436809732;857.9190220322439;870.8388948044632;906.7370462565914;916.8991410944791;5157.587393008625;5173;5177.249298851645;5181;5204.635675502678;4813.422389313305;5413.945860541344;5597.333746396117;5710.2862142232225;5715.256846661725;4894.412670479565;5045.571126454219;5075.162598171334;5212.374515058217;5306.204069389622;2554.786476423721;2668.6530751002247;3014.894478693235;3034.908952731422;3040.4071206115036;5255.737213139328;5641.633293835969;5727.878306323064;5991.5206783457215;6013.879722405527;4386.978065109654;5036.294918711384;5460.87026059837;5809.334904229997;5915.467607915222;5777.728899642072;5914.585978981516;5927.31835838884;5939;5973.402659733992;4754.762261886961;4816.638752093537;5323.361196656426;5711.914321285101;5793.246877906014;5296.947030529645;5796.956547821433;5801.477866991991;5822.82531524055;5864.912026319524;5380.43505431924;5702.8289151325525;5812.338226917184;5857.326407463196;6046.667433291212;3195.344954284413;3888.1446081861945;3920.1179734559496;3949.1114499238593;3965.068208970947;5151.613628977767;5156.143788236157;5496.0931446310915;5587.44125587438;5922.763089476372;5580.637258578179;5660;5665.405132461032;5676.679142701566;5777.335606405317;4735.857924262277;5561.610687251854;5583.637063590853;5630.4369563045;5699.401562835843;4810.59109975642;5311.415744268204;5498.505134537911;5531.197976293495;5553.9725150847225;5460;5562.694051827027;5579.302587176603;5635.746391412478;5649;5249.897521520524;5474;5492.835214943558;5506.301912063141;5526.613137080527 03:25:26 INFO - PROCESS | 2556 | |5;yui.html;748.5994992678916;781.8311624121935;830.5764060329237;836.3267569606464;838.8364268967551;15143.469978550753;16396.75404868925;16413.589660258498;16429.096399698014;16458.83541164588;14817.481388151416;14901;14923.925380373083;14952.252387380615;14976.850231497681;704.4223736536043;731.6817184524722;747.2378174262262;884.0761404332488;907.346710368537;894.575076838503;895.154079394972;896.4666023715879;896.4666023715879;897.0356866368647;924.8150369926008;942.519315149272;943.9103285187896;944.9763755906123;947.3889341374809;732.2055569706821;924.2559739409772;935.6865450074199;942.9528523573828;954.6086104697041;701.8982247574124;701.9964900175518;703.644659446976;704.4153352717233;707.253847191213;15039.248037598076;15115.470958516398;15117.39530418782;15119.16844573548;15119.244037798066;13954.372053257526;14140.858591414057;14170;14263.215523146222;14372.856271437257;12620.55828046023;13172;13258.801117983237;13387.665308367272;13422.127561708556;4543.091381723635;4584.633229341644;4706.929396059062;4824.228123500223;4865.978144589605;92805.07194928032;93413.06586934111;93482.53258733731;93664;93807.59288610745;10187;10765.677029689123;11276.548938042386;11316.207865449504;11777.587784427504;22547.88726056359;22574.209902653656;22596.774032259465;22613.88693056557;23267.06931722746;7962.283394494523;7998.12020677725;8040;8094.190580941977;8165.63254653542;4394.384786129945;4505.189065968158;4507.5267096955;4519.909601808011;4674.859754207379;3464.7574669772875;3562.038249672574;3580.749347545699;3581.8567257309937;3590.1204204969426;599.1851082527759;658.3285049249738;664.315754772585;665.5474277491255;668.3083009085573;541.1936215039563;594.304663543655;607.1863702638485;630.8706715123388;646.731606383355;8733.694320698745;8733.912660873437;9194.770130746785;9270.629174833066;9376.57805398745;559.6474221240593;615.6275453350659;617.8362733875526;636.7834936121741;638.2723694987718;3444.9492904663816;4055.8174882130374;4237.385579091077;4515.503294637587;4610.446746390455;90021.54989225011;90115.09884901198;90122.54938725264;138606.30696846452;139153.30423348022;99600.50599241017;127494.36252818677;129492.70507294995;130543;131357;107758;111159.44420277847;111430.44284778525;113670.86329136603;118549.40725296318;7514.624268786648;9908.702738917844;9927.503624818875;9975.551100200364;10066.949665251626;54396.728016359666;62692.79032911091;64231;65997;66599.66700166468;78931.60534197293;89875.20249594841;94000.58999114882;101026;102698;14121.717565648834;14316.212608306643;14465.638359041108;14613.634659133606;14920.776188356966;13263.403146858222;14104.576862694135;14985.850141498882;15103.924480377527;15446.922765386102;13431.395587198404;13725.588232353046;14299.428022878968;14301.499447519287;14368.56894293131;14060.156390616596;14243.287835608386;14292.35684394184;14535.200563969083;14870.702585948004;13528.255945923069;13639.79540306916;13717.314134293047;14352.784708229594;14436.206008669624;9495.715128546155;10647.988441098083;11010.119190464904;11112.444377780917;11959.282443053444;11139.443027848738;11399.771587466972;11481.138914581607;11631;11680 03:25:26 INFO - PROCESS | 2556 | __end_tp_report 03:25:26 INFO - PROCESS | 2556 | __start_cc_report 03:25:26 INFO - PROCESS | 2556 | _x_x_mozilla_cycle_collect,1615 03:25:26 INFO - PROCESS | 2556 | __end_cc_report 03:25:26 INFO - PROCESS | 2556 | __startTimestamp1469010326519__endTimestamp 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | ------- Summary: start ------- 03:25:26 INFO - PROCESS | 2556 | Number of tests: 6 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | [#0] dojo.html Cycles:165 Average:5691.77 Median:5840.50 stddev:632.96 (10.8%) stddev-sans-first:606.82 03:25:26 INFO - PROCESS | 2556 | Values: 3315.1 3325.1 3329.8 3350.5 3397.7 5530.9 5580.1 5598.9 5655.4 5702.4 5602.6 5674.6 5727.8 5735.7 5735.7 5105.7 5108.4 5169.5 5195.5 5253.8 5232.7 5274.3 5277.4 5285.1 5306.7 5109.4 5153.4 5166.5 5193.3 5210.6 5131.4 5136.6 5149.7 5202.3 5210.8 5109.7 5117.3 5137.9 5185.7 5254.3 5572.1 5674.4 5763.6 5772.5 5779.0 6096.7 6151.2 6163.7 6199.9 6211.9 6100.6 6235.8 6277.8 6306.9 6312.1 5581.5 5625.0 5790.2 5799.2 5803.2 6315.4 6360.5 6365.6 6366.9 6380.2 6073.7 6077.1 6092.6 6096.0 6096.4 6104.1 6134.6 6248.1 6250.3 6266.4 6086.1 6170.3 6184.4 6188.6 6192.2 5147.5 6168.5 6212.3 6232.1 6236.9 4773.7 4840.2 4857.9 4887.5 4892.3 4423.0 4441.1 4464.2 4465.0 4472.2 5584.6 5633.2 5634.7 5643.2 5672.7 6213.9 6216.5 6218.4 6235.3 6247.8 5605.4 5644.5 5654.2 5672.0 5696.2 5240.7 5311.9 5313.1 5365.2 5366.5 5890.5 6357.7 6359.4 6372.7 6380.8 6185.5 6209.1 6238.3 6248.3 6257.7 5299.3 6241.6 6304.6 6352.8 6354.2 6130.3 6148.4 6166.5 6166.7 6222.6 6056.3 6205.0 6206.1 6225.9 6233.9 5812.9 5895.6 5900.6 5926.2 5969.9 5906.4 5931.5 5932.3 5943.0 5949.9 5836.6 5869.0 5883.0 5890.2 5905.2 5952.8 5995.2 6002.2 6012.9 6027.8 5692.8 5752.4 5764.0 5840.5 5843.2 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | [#1] ext.html Cycles:180 Average:72250.18 Median:41706.58 stddev:161806.50 (388.0%) stddev-sans-first:162170.73 03:25:26 INFO - PROCESS | 2556 | Values: 500.1 515.3 519.0 524.6 527.4 29709.0 30280.7 40960.4 42427.8 42586.0 43346.1 43544.7 43670.0 43782.1 43940.3 9731.7 9888.5 9889.3 9896.8 9900.1 9704.0 9823.0 9839.2 9854.2 9869.4 9709.5 9837.3 9866.8 9871.3 9880.3 9791.0 9831.7 9848.0 9853.6 9870.2 9741.0 9807.5 9809.6 9828.9 9837.5 41441.2 41636.6 41725.4 41745.6 41823.2 3681.9 3915.6 4029.7 4213.7 4352.4 31671.2 32085.4 32220.5 32803.7 32851.7 3234.1 3248.6 3267.6 3371.8 3379.8 523738.0 526714.0 527070.0 527469.0 527902.0 58423.4 58883.4 58930.5 58945.7 59043.4 34617.5 35093.8 35325.0 36561.8 36658.5 16487.1 16545.3 16711.8 16730.7 16738.5 12751.5 12839.7 12858.3 12858.8 12919.1 19399.3 19444.0 19563.2 19590.1 19606.0 8024.5 8164.0 8173.0 8175.2 8230.7 872.0 907.8 952.8 962.6 1105.6 30178.0 30310.8 30336.7 30775.2 31071.5 757.0 1036.8 1086.1 1098.7 1100.7 15153.8 15640.1 15770.6 15922.0 16228.6 711532.0 719885.0 722224.0 723447.0 727591.0 59612.4 60031.0 60128.7 60148.7 60343.0 1270.6 1401.2 1401.8 1432.1 1433.1 26915.7 31252.8 31455.1 31597.2 31689.4 40936.6 50088.7 50112.7 50188.5 50222.0 531047.0 546316.0 550590.0 550666.0 550778.0 23441.4 23965.2 26063.1 26320.9 26559.5 48629.3 48942.5 49731.0 49961.3 50176.7 38998.0 40563.0 41360.2 42310.8 42770.8 40096.8 40857.0 41037.4 41687.8 41775.2 34241.8 37586.6 38439.4 38785.8 39030.2 33423.5 35209.6 35215.1 35310.6 36307.1 25382.1 25437.2 25547.4 26037.6 26325.0 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | [#2] jquery.html Cycles:180 Average:33607.04 Median:1413.45 stddev:95798.51 (6777.6%) stddev-sans-first:96034.73 03:25:26 INFO - PROCESS | 2556 | Values: 361.2 382.2 389.7 396.1 411.9 15703.3 16958.5 20122.8 21275.8 21341.9 20588.9 20868.7 20919.3 20937.7 20962.0 5755.6 5863.7 6031.5 6139.9 6141.8 5933.7 5957.4 6009.7 6024.5 6028.1 5076.2 5757.8 5961.4 5962.0 6085.2 6066.2 6081.7 6082.9 6083.5 6102.5 5034.0 5338.9 5341.7 5362.6 5385.4 12625.9 12669.7 12796.4 12939.5 12990.5 2152.7 2200.8 2285.9 2371.0 2465.7 9987.1 10168.6 10281.5 10370.9 13727.2 1742.6 1890.8 1905.6 1934.0 1996.2 103623.0 113539.0 122103.4 124667.4 134875.9 9721.5 11107.0 11193.4 11242.8 11470.5 14198.6 14288.8 14326.1 14326.4 14338.6 8395.5 8588.4 8628.2 8689.5 8723.2 6856.3 8363.4 8568.1 8691.0 8739.3 3828.6 4533.2 4537.5 4541.5 4602.7 1873.3 1913.9 1914.2 1923.9 1928.8 873.2 962.7 963.4 977.7 988.1 16592.6 21261.5 21263.6 21398.0 21398.9 795.8 942.7 957.1 981.7 1072.3 10683.1 10878.4 11040.6 11062.1 11147.0 557044.0 566418.0 571879.0 576900.0 598694.0 74268.6 74540.0 108274.0 119592.4 136821.0 833.3 933.2 940.8 972.7 975.6 14739.6 18701.0 18713.6 18721.4 18799.4 55004.2 55537.4 55797.0 55846.0 56499.0 83451.6 84135.2 84865.0 85018.0 85159.0 6612.5 7870.6 7927.3 7988.4 8204.4 12242.4 13076.2 13353.3 13499.4 13654.9 11046.9 11341.1 11451.6 11525.8 11689.7 11372.5 11426.4 11505.1 11543.2 11563.7 11667.9 11739.6 11778.4 11935.8 12002.2 8080.8 8128.2 8217.1 8259.3 8473.3 9797.3 11436.6 11525.0 11677.4 11744.6 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | [#3] mootools.html Cycles:180 Average:5738.42 Median:4825.80 stddev:3205.13 (66.4%) stddev-sans-first:3198.65 03:25:26 INFO - PROCESS | 2556 | Values: 1548.1 1599.6 1784.8 1877.7 1915.2 7867.1 8081.6 8532.5 9006.4 9020.2 7985.0 8070.7 8430.3 8662.2 8671.3 2346.9 2354.7 2388.1 2389.3 2395.4 2086.9 2355.6 2371.3 2397.5 2402.1 1950.2 2132.1 2284.4 2340.1 2354.7 2324.2 2342.5 2346.8 2347.1 2349.4 2324.1 2357.3 2358.5 2361.7 2362.3 8959.0 9022.9 9031.8 9040.7 9074.3 5243.5 5407.1 5424.1 5424.3 5429.8 3465.4 3545.1 3628.7 3643.8 3654.5 1066.4 1079.0 1089.5 1099.9 1102.8 9761.8 11844.2 11848.0 11860.0 11882.3 3699.3 4608.1 4786.7 4825.2 4826.4 10001.9 10006.5 10009.0 10015.0 10023.9 5321.6 5322.1 5324.7 5325.2 5332.3 4521.3 4738.3 4858.2 4861.6 4865.2 3334.5 3667.9 3921.5 3957.3 4054.9 1104.9 1207.3 1212.8 1231.5 1258.2 733.3 880.4 945.4 956.3 959.2 6040.0 6157.3 6178.0 6181.3 6198.8 905.8 979.1 1018.0 1038.9 1067.7 4067.6 4072.6 4109.7 4199.7 4205.9 10446.8 10754.7 10869.6 10876.8 10902.2 9034.2 10780.0 10798.5 10803.7 10806.5 7333.0 7949.7 8279.8 8299.0 8424.8 6093.6 6103.2 6162.8 6259.7 6335.1 9969.7 10142.7 10274.6 10288.5 10293.4 10409.4 10415.0 10433.3 10434.9 10456.7 7067.8 7159.3 7208.7 7226.8 7247.1 8389.2 8390.5 8493.9 8515.0 8516.4 7379.5 7536.0 7609.4 7740.7 7843.9 7260.6 7461.3 7518.1 7565.0 7678.3 6006.9 7064.4 7069.8 7125.1 7129.9 4942.9 5107.0 5116.2 5171.6 5238.8 5123.2 5132.7 5138.1 5159.3 5327.5 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | [#4] prototype.html Cycles:180 Average:4524.58 Median:5158.07 stddev:1400.96 (27.2%) stddev-sans-first:1368.65 03:25:26 INFO - PROCESS | 2556 | Values: 306.4 310.7 315.2 320.3 323.1 4350.8 4441.5 5269.2 5314.5 5316.2 4581.3 5318.6 5342.8 5358.9 5380.5 3480.7 3806.8 4016.5 4020.0 4085.2 3510.5 3915.0 3936.8 4065.8 4095.9 3323.0 3993.2 4046.6 4050.1 4066.7 3027.3 3577.6 4080.2 4104.9 4109.9 3345.3 3420.5 3460.9 3535.6 3549.3 5130.5 5145.4 5146.9 5149.5 5158.6 4618.9 4624.8 4626.5 4642.1 4645.0 5295.8 5526.8 5531.4 5537.9 5566.9 3242.2 3879.7 3880.2 3912.5 3915.3 4867.5 5329.2 5537.5 5685.5 5844.6 3333.4 3344.5 3473.7 3926.2 3947.6 3463.5 3988.9 4018.5 4036.8 4062.5 4306.8 4643.8 4666.0 4671.7 4688.9 5604.2 5636.9 5646.5 5662.3 5694.6 1322.2 1343.5 1350.2 1360.4 1366.2 805.6 857.9 870.8 906.7 916.9 5157.6 5173.0 5177.2 5181.0 5204.6 4813.4 5413.9 5597.3 5710.3 5715.3 4894.4 5045.6 5075.2 5212.4 5306.2 2554.8 2668.7 3014.9 3034.9 3040.4 5255.7 5641.6 5727.9 5991.5 6013.9 4387.0 5036.3 5460.9 5809.3 5915.5 5777.7 5914.6 5927.3 5939.0 5973.4 4754.8 4816.6 5323.4 5711.9 5793.2 5296.9 5797.0 5801.5 5822.8 5864.9 5380.4 5702.8 5812.3 5857.3 6046.7 3195.3 3888.1 3920.1 3949.1 3965.1 5151.6 5156.1 5496.1 5587.4 5922.8 5580.6 5660.0 5665.4 5676.7 5777.3 4735.9 5561.6 5583.6 5630.4 5699.4 4810.6 5311.4 5498.5 5531.2 5554.0 5460.0 5562.7 5579.3 5635.7 5649.0 5249.9 5474.0 5492.8 5506.3 5526.6 03:25:26 INFO - PROCESS | 2556 | 03:25:26 INFO - PROCESS | 2556 | [#5] yui.html Cycles:180 Average:23523.82 Median:4315.89 stddev:35676.75 (826.6%) stddev-sans-first:35735.85 03:25:26 INFO - PROCESS | 2556 | Values: 748.6 781.8 830.6 836.3 838.8 15143.5 16396.8 16413.6 16429.1 16458.8 14817.5 14901.0 14923.9 14952.3 14976.9 704.4 731.7 747.2 884.1 907.3 894.6 895.2 896.5 896.5 897.0 924.8 942.5 943.9 945.0 947.4 732.2 924.3 935.7 943.0 954.6 701.9 702.0 703.6 704.4 707.3 15039.2 15115.5 15117.4 15119.2 15119.2 13954.4 14140.9 14170.0 14263.2 14372.9 12620.6 13172.0 13258.8 13387.7 13422.1 4543.1 4584.6 4706.9 4824.2 4866.0 92805.1 93413.1 93482.5 93664.0 93807.6 10187.0 10765.7 11276.5 11316.2 11777.6 22547.9 22574.2 22596.8 22613.9 23267.1 7962.3 7998.1 8040.0 8094.2 8165.6 4394.4 4505.2 4507.5 4519.9 4674.9 3464.8 3562.0 3580.7 3581.9 3590.1 599.2 658.3 664.3 665.5 668.3 541.2 594.3 607.2 630.9 646.7 8733.7 8733.9 9194.8 9270.6 9376.6 559.6 615.6 617.8 636.8 638.3 3444.9 4055.8 4237.4 4515.5 4610.4 90021.5 90115.1 90122.5 138606.3 139153.3 99600.5 127494.4 129492.7 130543.0 131357.0 107758.0 111159.4 111430.4 113670.9 118549.4 7514.6 9908.7 9927.5 9975.6 10066.9 54396.7 62692.8 64231.0 65997.0 66599.7 78931.6 89875.2 94000.6 101026.0 102698.0 14121.7 14316.2 14465.6 14613.6 14920.8 13263.4 14104.6 14985.9 15103.9 15446.9 13431.4 13725.6 14299.4 14301.5 14368.6 14060.2 14243.3 14292.4 14535.2 14870.7 13528.3 13639.8 13717.3 14352.8 14436.2 9495.7 10648.0 11010.1 11112.4 11959.3 11139.4 11399.8 11481.1 11631.0 11680.0 03:25:26 INFO - PROCESS | 2556 | -------- Summary: end -------- 03:25:26 INFO - PROCESS | 2556 | 03:25:27 INFO - TEST-INFO | 2556: exit 0 03:25:27 INFO - TEST-OK | dromaeo_css | took 1116627ms 03:25:27 INFO - TEST-START | kraken 03:25:27 INFO - operating with platform_type : mac_ 03:25:27 INFO - Initialising browser for kraken test... 03:25:27 INFO - TEST-INFO | started process 2559 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpIQ05zy/profile http://localhost:55006/getInfo.html) 03:25:29 INFO - PROCESS | 2559 | __metrics Screen width/height:1600/1200 03:25:29 INFO - PROCESS | 2559 | colorDepth:24 03:25:29 INFO - PROCESS | 2559 | Browser inner width/height: 1024/689 03:25:29 INFO - PROCESS | 2559 | __metrics 03:25:29 INFO - PROCESS | 2559 | 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:25:29 INFO - PROCESS | 2559 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:25:30 INFO - TEST-INFO | 2559: exit 0 03:25:30 INFO - Browser initialized. 03:25:30 INFO - Running cycle 1/1 for kraken test... 03:25:30 INFO - TEST-INFO | started process 2560 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpIQ05zy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:42 INFO - PROCESS | 2560 | RSS: Main: 245624832 03:25:42 INFO - PROCESS | 2560 | 03:25:44 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55006/tests/kraken/driver.html?testName=audio-beat-detection) 03:25:45 INFO - PROCESS | 2560 | RSS: Main: 295940096 03:25:45 INFO - PROCESS | 2560 | 03:25:48 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55006/tests/kraken/driver.html?testName=audio-dft) 03:25:48 INFO - PROCESS | 2560 | RSS: Main: 305418240 03:25:48 INFO - PROCESS | 2560 | 03:25:52 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55006/tests/kraken/driver.html?testName=audio-fft) 03:25:53 INFO - PROCESS | 2560 | RSS: Main: 388239360 03:25:53 INFO - PROCESS | 2560 | 03:25:55 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55006/tests/kraken/driver.html?testName=audio-oscillator) 03:25:55 INFO - PROCESS | 2560 | RSS: Main: 292737024 03:25:55 INFO - PROCESS | 2560 | 03:25:57 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55006/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:25:58 INFO - PROCESS | 2560 | RSS: Main: 296849408 03:25:58 INFO - PROCESS | 2560 | 03:26:01 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55006/tests/kraken/driver.html?testName=imaging-darkroom) 03:26:01 INFO - PROCESS | 2560 | RSS: Main: 332857344 03:26:01 INFO - PROCESS | 2560 | 03:26:04 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55006/tests/kraken/driver.html?testName=imaging-desaturate) 03:26:05 INFO - PROCESS | 2560 | RSS: Main: 336441344 03:26:05 INFO - PROCESS | 2560 | 03:26:08 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55006/tests/kraken/driver.html?testName=json-parse-financial) 03:26:09 INFO - PROCESS | 2560 | RSS: Main: 334626816 03:26:09 INFO - PROCESS | 2560 | 03:26:10 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55006/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:26:11 INFO - PROCESS | 2560 | RSS: Main: 314150912 03:26:11 INFO - PROCESS | 2560 | 03:26:13 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:26:13 INFO - PROCESS | 2560 | RSS: Main: 321187840 03:26:13 INFO - PROCESS | 2560 | 03:26:15 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:26:16 INFO - PROCESS | 2560 | RSS: Main: 318279680 03:26:16 INFO - PROCESS | 2560 | 03:26:19 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:26:20 INFO - PROCESS | 2560 | RSS: Main: 327376896 03:26:20 INFO - PROCESS | 2560 | 03:26:23 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:26:23 INFO - PROCESS | 2560 | RSS: Main: 328818688 03:26:23 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | Cycle 1(1): loaded http://localhost:55006/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55006/tests/kraken/driver.html?testName=ai-astar) 03:26:25 INFO - PROCESS | 2560 | RSS: Main: 372662272 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | __start_tp_report 03:26:25 INFO - PROCESS | 2560 | _x_x_mozilla_page_load 03:26:25 INFO - PROCESS | 2560 | _x_x_mozilla_page_load_details 03:26:25 INFO - PROCESS | 2560 | |i|pagename|runs| 03:26:25 INFO - PROCESS | 2560 | |0;ai-astar;100;113;112;113;114;113;113;113;113;114 03:26:25 INFO - PROCESS | 2560 | |1;audio-beat-detection;143;111;107;161;107;107;129;106;140;111 03:26:25 INFO - PROCESS | 2560 | |2;audio-dft;188;186;184;183;184;182;182;182;186;185 03:26:25 INFO - PROCESS | 2560 | |3;audio-fft;75;80;81;110;83;58;58;64;81;58 03:26:25 INFO - PROCESS | 2560 | |4;audio-oscillator;106;112;128;103;71;69;98;70;68;101 03:26:25 INFO - PROCESS | 2560 | |5;imaging-gaussian-blur;88;87;89;86;88;85;90;86;88;85 03:26:25 INFO - PROCESS | 2560 | |6;imaging-darkroom;95;95;93;95;94;94;96;95;94;94 03:26:25 INFO - PROCESS | 2560 | |7;imaging-desaturate;88;87;87;84;85;85;101;132;134;84 03:26:25 INFO - PROCESS | 2560 | |8;json-parse-financial;54;52;53;52;53;53;53;52;53;53 03:26:25 INFO - PROCESS | 2560 | |9;json-stringify-tinderbox;65;73;79;78;77;71;72;72;71;73 03:26:25 INFO - PROCESS | 2560 | |10;stanford-crypto-aes;83;83;83;97;98;85;81;85;86;83 03:26:25 INFO - PROCESS | 2560 | |11;stanford-crypto-ccm;138;137;136;141;144;147;147;147;196;154 03:26:25 INFO - PROCESS | 2560 | |12;stanford-crypto-pbkdf2;173;170;172;170;171;171;172;174;173;171 03:26:25 INFO - PROCESS | 2560 | |13;stanford-crypto-sha256-iterative;70;68;69;70;85;90;89;85;67;67 03:26:25 INFO - PROCESS | 2560 | __end_tp_report 03:26:25 INFO - PROCESS | 2560 | __start_cc_report 03:26:25 INFO - PROCESS | 2560 | _x_x_mozilla_cycle_collect,2083 03:26:25 INFO - PROCESS | 2560 | __end_cc_report 03:26:25 INFO - PROCESS | 2560 | __startTimestamp1469010385818__endTimestamp 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | ------- Summary: start ------- 03:26:25 INFO - PROCESS | 2560 | Number of tests: 14 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:4.18 (3.7%) stddev-sans-first:0.60 03:26:25 INFO - PROCESS | 2560 | Values: 100.0 113.0 112.0 113.0 114.0 113.0 113.0 113.0 113.0 114.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:120.00 stddev:19.74 (16.5%) stddev-sans-first:19.45 03:26:25 INFO - PROCESS | 2560 | Values: 143.0 111.0 107.0 161.0 107.0 107.0 129.0 106.0 140.0 111.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:2.04 (1.1%) stddev-sans-first:1.64 03:26:25 INFO - PROCESS | 2560 | Values: 188.0 186.0 184.0 183.0 184.0 182.0 182.0 182.0 186.0 185.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#3] audio-fft Cycles:10 Average:74.80 Median:77.50 stddev:16.24 (21.0%) stddev-sans-first:17.22 03:26:25 INFO - PROCESS | 2560 | Values: 75.0 80.0 81.0 110.0 83.0 58.0 58.0 64.0 81.0 58.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:68.50 stddev:21.49 (31.4%) stddev-sans-first:22.24 03:26:25 INFO - PROCESS | 2560 | Values: 106.0 112.0 128.0 103.0 71.0 69.0 98.0 70.0 68.0 101.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.69 (1.9%) stddev-sans-first:1.76 03:26:25 INFO - PROCESS | 2560 | Values: 88.0 87.0 89.0 86.0 88.0 85.0 90.0 86.0 88.0 85.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 03:26:25 INFO - PROCESS | 2560 | Values: 95.0 95.0 93.0 95.0 94.0 94.0 96.0 95.0 94.0 94.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:85.00 stddev:19.77 (23.3%) stddev-sans-first:20.71 03:26:25 INFO - PROCESS | 2560 | Values: 88.0 87.0 87.0 84.0 85.0 85.0 101.0 132.0 134.0 84.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:0.63 (1.2%) stddev-sans-first:0.50 03:26:25 INFO - PROCESS | 2560 | Values: 54.0 52.0 53.0 52.0 53.0 53.0 53.0 52.0 53.0 53.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.00 stddev:4.09 (5.6%) stddev-sans-first:3.12 03:26:25 INFO - PROCESS | 2560 | Values: 65.0 73.0 79.0 78.0 77.0 71.0 72.0 72.0 71.0 73.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:85.00 stddev:6.02 (7.1%) stddev-sans-first:6.26 03:26:25 INFO - PROCESS | 2560 | Values: 83.0 83.0 83.0 97.0 98.0 85.0 81.0 85.0 86.0 83.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:147.00 stddev:17.54 (11.9%) stddev-sans-first:18.17 03:26:25 INFO - PROCESS | 2560 | Values: 138.0 137.0 136.0 141.0 144.0 147.0 147.0 147.0 196.0 154.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 03:26:25 INFO - PROCESS | 2560 | Values: 173.0 170.0 172.0 170.0 171.0 171.0 172.0 174.0 173.0 171.0 03:26:25 INFO - PROCESS | 2560 | 03:26:25 INFO - PROCESS | 2560 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:77.50 stddev:9.85 (12.7%) stddev-sans-first:10.21 03:26:25 INFO - PROCESS | 2560 | Values: 70.0 68.0 69.0 70.0 85.0 90.0 89.0 85.0 67.0 67.0 03:26:25 INFO - PROCESS | 2560 | -------- Summary: end -------- 03:26:25 INFO - PROCESS | 2560 | 03:26:26 INFO - TEST-INFO | 2560: exit 0 03:26:26 INFO - TEST-OK | kraken | took 59517ms 03:26:26 INFO - SUITE-END | took 1176s 03:26:26 INFO - Completed test suite (00:19:36) 03:26:26 INFO - javascript benchmark 03:26:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.088350703557, 3325.0523600773786, 3329.8002119872795, 3350.4806754952983, 3397.728181745459, 5530.889382212354, 5580.079286917654, 5598.888022239553, 5655.434456554348, 5702.372738998707, 5602.60781745278, 5674.574406919477, 5727.799527016556, 5735.684537350444, 5735.741891614877, 5105.668131571455, 5108.438071812097, 5169.457206993268, 5195.532402083812, 5253.763580638862, 5232.659877107976, 5274.287971123907, 5277.445868183843, 5285.075111855429, 5306.70813105279, 5109.412417571971, 5153.3815942087185, 5166.509181627739, 5193.298904647881, 5210.557102646279, 5131.358580177478, 5136.563392111675, 5149.6910185389, 5202.3497062867145, 5210.817621383233, 5109.744512774347, 5117.334746482933, 5137.871553211162, 5185.662931909414, 5254.343207099113, 5572.0527510323345, 5674.375818659944, 5763.596548241625, 5772.509336706385, 5779.017567013576, 6096.683260361079, 6151.231096112986, 6163.691815409212, 6199.938000619981, 6211.9068213976825, 6100.572959892809, 6235.781747638856, 6277.780277690259, 6306.905396419057, 6312.147860038882, 5581.49766521015, 5624.959382514248, 5790.189373487715, 5799.246098007275, 5803.21656576361, 6315.431611155018, 6360.459360954278, 6365.554411191265, 6366.904496432558, 6380.170577824853, 6073.681704558921, 6077.118817771398, 6092.555372131625, 6095.994160963392, 6096.3903609639565, 6104.084387341934, 6134.631922084689, 6248.125262463259, 6250.343713910065, 6266.373362663789, 6086.147939288503, 6170.259568851767, 6184.443400094013, 6188.628682279078, 6192.225971753531, 5147.459516750687, 6168.537359697951, 6212.316645169028, 6232.096346029891, 6236.937630623726, 4773.71357718538, 4840.249761287007, 4857.9028419431415, 4887.486813884562, 4892.339534162842, 4422.977885110554, 4441.089576636782, 4464.218761716752, 4464.977675111669, 4472.217361961643, 5584.5811564132855, 5633.211350410946, 5634.718264086861, 5643.1535269709875, 5672.716364181857, 6213.937860621426, 6216.533759968021, 6218.440340369389, 6235.314115447297, 6247.75008999644, 5605.383407825136, 5644.463775941279, 5654.180143879114, 5672.0, 5696.174054762037, 5240.65935714185, 5311.920321195185, 5313.07021271276, 5365.222042803772, 5366.543843773245, 5890.4698577128265, 6357.682115894279, 6359.395857393541, 6372.745090196433, 6380.776672816429, 6185.474234690102, 6209.130721699056, 6238.344973777688, 6248.343923888108, 6257.68711564429, 5299.311089558333, 6241.563090583615, 6304.621722696653, 6352.809415717536, 6354.237491500865, 6130.295016073031, 6148.44663980244, 6166.537509686702, 6166.722497487534, 6222.564420490522, 6056.333803281547, 6205.0, 6206.100115483139, 6225.906611400742, 6233.906491402723, 5812.854678633068, 5895.587308888336, 5900.616459930091, 5926.2295901532525, 5969.880602388015, 5906.40935906406, 5931.525477961839, 5932.347441781487, 5943.0, 5949.940500594938, 5836.620619659709, 5869.031609784509, 5883.0293001654245, 5890.2342695449315, 5905.202797622266, 5952.761889524544, 5995.220621319375, 6002.159697642246, 6012.939870601238, 6027.8191654248685, 5692.7722891083895, 5752.395998420106, 5764.0, 5840.503557197516, 5843.152742852178], "value": 5651.578952356232, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.0823488897872, 515.319777893181, 519.0242344392542, 524.5881982643625, 527.3855957809153, 29709.0, 30280.697193028063, 40960.38559421604, 42427.78786106065, 42585.95433433819, 43346.13307733843, 43544.69365919028, 43670.0, 43782.12435751283, 43940.340894886605, 9731.659391921301, 9888.505574721272, 9889.307748457612, 9896.752581185492, 9900.108990190882, 9703.95148024259, 9822.968588298232, 9839.163671087967, 9854.162396196332, 9869.358491698016, 9709.514524273793, 9837.311388202794, 9866.753331166743, 9871.259655525864, 9880.25898057645, 9790.951045244763, 9831.705048848546, 9848.0, 9853.605855765762, 9870.2103831694, 9741.0, 9807.509624518816, 9809.558569864374, 9828.852567211427, 9837.508124593742, 41441.1711765763, 41636.58363416357, 41725.37411938823, 41745.582544174475, 41823.163536729095, 3681.907952301187, 3915.647591716759, 4029.657479114279, 4213.683973701954, 4352.36890650857, 31671.20821979446, 32085.358292834248, 32220.516692249636, 32803.6719632803, 32851.6714832851, 3234.110619579611, 3248.642649308574, 3267.640559538449, 3371.844159961958, 3379.797212167278, 523738.0, 526714.0, 527070.0, 527469.0, 527902.0, 58423.41576584265, 58883.411165887796, 58930.52673683107, 58945.705271473365, 59043.40956590379, 34617.48073778845, 35093.82453087718, 35325.0, 36561.81719091388, 36658.45012324817, 16487.093209873332, 16545.25546350393, 16711.83288167127, 16730.74903876443, 16738.497845064667, 12751.48994040228, 12839.743205136032, 12858.292793896238, 12858.807117893051, 12919.095663303478, 19399.321023764383, 19444.0, 19563.197490283772, 19590.118444670024, 19606.0, 8024.518528888286, 8164.0, 8173.038470766973, 8175.223353781381, 8230.711925082713, 872.0, 907.847034266491, 952.8475443928938, 962.552413127899, 1105.6019832860163, 30178.0, 30310.84844575763, 30336.69663303383, 30775.23061923425, 31071.533926991113, 756.9583672898043, 1036.7719101797593, 1086.1256688365784, 1098.7143342730947, 1100.6532942123206, 15153.848461515465, 15640.139792311305, 15770.60573485649, 15922.044677319398, 16228.59428514273, 711532.0, 719885.0, 722224.0, 723447.0, 727591.0, 59612.40387596155, 60031.0, 60128.69935650294, 60148.69925650344, 60343.0, 1270.6251655761573, 1401.1663060478795, 1401.7897315402772, 1432.0548438031058, 1433.118632041308, 26915.730842691322, 31252.843735781174, 31455.056348309587, 31597.21006974752, 31689.36621267608, 40936.59063409328, 50088.74955625198, 50112.74943625258, 50188.498115019844, 50222.0, 531047.0, 546316.0, 550590.0, 550666.0, 550778.0, 23441.41396465102, 23965.16121935724, 26063.08779192719, 26320.8683956579, 26559.468810624065, 48629.27056094232, 48942.51057489379, 49731.0, 49961.25290603292, 50176.749116254185, 38998.0, 40563.0, 41360.1727965433, 42310.78844605757, 42770.78614606907, 40096.79951600223, 40856.978575535846, 41037.38443923284, 41687.791561042, 41775.1644967105, 34241.828790855885, 37586.624133759404, 38439.423408649454, 38785.80607096946, 39030.2193956125, 33423.49864752079, 35209.64790352166, 35215.11962200965, 35310.646893530735, 36307.092322692144, 25382.111626093, 25437.236882893543, 25547.36131596725, 26037.609435858856, 26325.0], "value": 20121.909185304212, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.22877656203997, 382.17259632894786, 389.69993105308913, 396.08109186686863, 411.9382092686097, 15703.293351799168, 16958.49124526263, 20122.798772012276, 21275.787242127575, 21341.893290533524, 20588.89705551474, 20868.686969695467, 20919.267825626106, 20937.685934710993, 20962.0, 5755.597108202427, 5863.736131874076, 6031.457168854802, 6139.87720245597, 6141.815745527619, 5933.732982015819, 5957.434043765857, 6009.729562169691, 6024.48791852693, 6028.095785632168, 5076.18780995039, 5757.827265182031, 5961.374055724152, 5961.970190149043, 6085.208922840025, 6066.181065556138, 6081.695915204266, 6082.9391706082815, 6083.513318934497, 6102.481289090432, 5033.974830125826, 5338.946610533883, 5341.732913354317, 5362.570994320445, 5385.380681221671, 12625.873741262561, 12669.74660506794, 12796.360181990956, 12939.48242070316, 12990.480380784757, 2152.720146380976, 2200.7579166291694, 2285.931422057324, 2370.9644355334513, 2465.6671349367784, 9987.101160895554, 10168.576399304104, 10281.485925703684, 10370.9481452593, 13727.176369417866, 1742.573069597944, 1890.754201953751, 1905.6474552207746, 1934.0, 1996.151635554898, 103623.0, 113539.0, 122103.38948305379, 124667.3766631161, 134875.88430867626, 9721.513924303898, 11107.000369966741, 11193.384363859903, 11242.831357529643, 11470.54117835294, 14198.574042778733, 14288.785668214778, 14326.140431574138, 14326.355314010894, 14338.56984290473, 8395.496270223684, 8588.355873309527, 8628.180322869317, 8689.522076285739, 8723.171298726611, 6856.314368563104, 8363.414560980673, 8568.10034946334, 8691.0, 8739.344549158839, 3828.559715632738, 4533.184026875128, 4537.523560026216, 4541.5231400703115, 4602.7008244464005, 1873.3162395725462, 1913.86602937793, 1914.15777058094, 1923.9038048097539, 1928.7878333383317, 873.2446433834688, 962.6630679262203, 963.4460185393286, 977.6773664690635, 988.1304452082122, 16592.585185370463, 21261.46846328823, 21263.574728505344, 21398.0, 21398.893005535185, 795.7572940253278, 942.707760594218, 957.1337939165066, 981.7005813227034, 1072.2601405030505, 10683.14534837211, 10878.377705401397, 11040.613578524873, 11062.115030797517, 11146.996770290712, 557044.0, 566418.0, 571879.0, 576900.0, 598694.0, 74268.62865685746, 74540.0, 108274.0, 119592.40203798925, 136821.0, 833.3250067445389, 933.2114363362947, 940.8071345374182, 972.7422233108232, 975.6048800235774, 14739.55781326562, 18701.0, 18713.567236259198, 18721.438356849318, 18799.36887477553, 55004.174937376774, 55537.444625553224, 55797.0, 55846.0, 56499.0, 83451.5827420859, 84135.15864841273, 84865.0, 85018.0, 85159.0, 6612.5040621952585, 7870.606469676608, 7927.326177274997, 7988.400869934662, 8204.425690201628, 12242.449089790802, 13076.215427074407, 13353.265570393722, 13499.39252733649, 13654.86345136536, 11046.944765276123, 11341.072017756836, 11451.599194028502, 11525.827112593486, 11689.707757306136, 11372.488238029144, 11426.371549564854, 11505.137114716263, 11543.249688770204, 11563.710907227387, 11667.883321166679, 11739.647810565355, 11778.411079445823, 11935.820962685393, 12002.21985570935, 8080.83838323242, 8128.227818357246, 8217.137200593852, 8259.339252859636, 8473.322134229371, 9797.265205109503, 11436.599719009795, 11525.020373268366, 11677.357745323749, 11744.58893938708], "value": 9370.372463666763, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1548.07889305863, 1599.6000999750063, 1784.7769028871392, 1877.7371168036468, 1915.1956178405078, 7867.134615192324, 8081.595920203981, 8532.488050716953, 9006.414583052105, 9020.188183063532, 7985.0, 8070.677172913076, 8430.283425908805, 8662.177093176138, 8671.262942649866, 2346.894389752457, 2354.72920614129, 2388.0686532252435, 2389.3429306940643, 2395.3652282145244, 2086.8643538170045, 2355.646653002055, 2371.3123194273726, 2397.520495900818, 2402.087206861399, 1950.2199120351831, 2132.0832042221837, 2284.4288927768057, 2340.0873659272897, 2354.682117914085, 2324.2097686786574, 2342.4612339161918, 2346.765323467657, 2347.119830063726, 2349.4243910242017, 2324.0819876148826, 2357.281029286067, 2358.4929240213264, 2361.681173041635, 2362.2795047510504, 8959.0, 9022.909770902272, 9031.774205644911, 9040.728778136667, 9074.319426043006, 5243.475652434726, 5407.107827208506, 5424.105022671294, 5424.294841670597, 5429.837104886859, 3465.42820434628, 3545.1468555033007, 3628.709703223762, 3643.7631553949172, 3654.543182102237, 1066.4134725900724, 1079.0073132717919, 1089.54239219528, 1099.8790133085354, 1102.7518808268155, 9761.755956101084, 11844.170908036442, 11848.0, 11860.0, 11882.28706277626, 3699.3341198583976, 4608.124456353282, 4786.736729479842, 4825.1555977703765, 4826.444958829779, 10001.949990250003, 10006.49967501622, 10009.049140331657, 10015.048570385801, 10023.899761002442, 5321.600879934021, 5322.0952438085615, 5324.680519168863, 5325.227841962894, 5332.2801421808335, 4521.321801729701, 4738.265568836767, 4858.222684370484, 4861.6353773467135, 4865.172920603506, 3334.549835772188, 3667.8716244931297, 3921.490206273223, 3957.2679054375035, 4054.8986275343354, 1104.9116070714324, 1207.2635692227734, 1212.8120141377924, 1231.5381731850557, 1258.1758947889148, 733.2557454184008, 880.3837313880182, 945.3760518058186, 956.2588993529931, 959.2230293462318, 6039.969800151059, 6157.337518869969, 6178.0, 6181.289151747516, 6198.845028874314, 905.7871400220943, 979.1432496565506, 1017.9898201018043, 1038.8832005594018, 1067.658349328223, 4067.633912947849, 4072.6131017553284, 4109.712320137622, 4199.664026877843, 4205.852795152155, 10446.843297350546, 10754.731131721614, 10869.619563315404, 10876.836847447295, 10902.182336324808, 9034.2320902724, 10780.029797318122, 10798.51406686701, 10803.729906752395, 10806.459677016117, 7333.01004364404, 7949.7217597383815, 8279.793005174919, 8299.045609754834, 8424.831503369898, 6093.634381937098, 6103.23709536308, 6162.815115546541, 6259.7167580646765, 6335.113084168128, 9969.70393848795, 10142.746431339276, 10274.589016439259, 10288.537015834156, 10293.43386113771, 10409.375437473776, 10415.010573995458, 10433.26967112295, 10434.895651043393, 10456.738581535523, 7067.752628657972, 7159.319864612853, 7208.675609597476, 7226.783196504113, 7247.094113235846, 8389.203025712548, 8390.496570205807, 8493.91506084956, 8514.957425212835, 8516.40385173032, 7379.520331178671, 7535.962320188364, 7609.39124869998, 7740.690372385042, 7843.882341764992, 7260.636968151677, 7461.253874612496, 7518.060242469692, 7565.0, 7678.308952194329, 6006.909896351471, 7064.434845212268, 7069.82325441868, 7125.1093613298335, 7129.928700712927, 4942.851714448572, 5106.974465127799, 5116.181410974226, 5171.63798534099, 5238.842834714965, 5123.180291153398, 5132.7433628317685, 5138.100832354397, 5159.277701121921, 5327.467253274796], "value": 4616.4131098951375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.4040441341591, 310.72811290121143, 315.2119700748129, 320.26978489044984, 323.1162769824531, 4350.825966961322, 4441.5336389679105, 5269.1832765921245, 5314.548263397611, 5316.20256961456, 4581.266997280436, 5318.553465405908, 5342.786288548463, 5358.946410535894, 5380.515753582177, 3480.7215422766244, 3806.7525610835346, 4016.5380981187245, 4019.9799001004953, 4085.22380747657, 3510.508528805969, 3914.965901426389, 3936.822842972059, 4065.780265920227, 4095.9180816383655, 3322.9833850830714, 3993.2013597280516, 4046.5751096134777, 4050.116695736482, 4066.654334381581, 3027.303720144357, 3577.5672542670973, 4080.245154646385, 4104.856330028435, 4109.91780164398, 3345.2974875276223, 3420.521127042216, 3460.8615655373756, 3535.5934067582043, 3549.343371476285, 5130.461301563357, 5145.434002259748, 5146.922796158061, 5149.536541711226, 5158.561522270612, 4618.861434156981, 4624.791884365211, 4626.4910859805395, 4642.117997580483, 4644.978104816935, 5295.7881684732565, 5526.778928842841, 5531.3915469298345, 5537.895599672099, 5566.860828479321, 3242.221866751986, 3879.72841901067, 3880.2239552089695, 3912.5304963404287, 3915.334393153171, 4867.495403309611, 5329.173978033411, 5537.529310008654, 5685.545156387479, 5844.603712477864, 3333.3999880021343, 3344.464885618338, 3473.7394695397443, 3926.2343842950922, 3947.6447119758786, 3463.4631632097066, 3988.880333589997, 4018.5378681452007, 4036.788963310995, 4062.471878655756, 4306.849260275875, 4643.814247430065, 4665.9766701166955, 4671.719696818202, 4688.906221875612, 5604.215409842625, 5636.915446268309, 5646.470955652717, 5662.348829884533, 5694.572907031907, 1322.2066759944148, 1343.5230493174977, 1350.1831392007891, 1360.4490181476517, 1366.2417358366004, 805.5569436809732, 857.9190220322439, 870.8388948044632, 906.7370462565914, 916.8991410944791, 5157.587393008625, 5173.0, 5177.249298851645, 5181.0, 5204.635675502678, 4813.422389313305, 5413.945860541344, 5597.333746396117, 5710.2862142232225, 5715.256846661725, 4894.412670479565, 5045.571126454219, 5075.162598171334, 5212.374515058217, 5306.204069389622, 2554.786476423721, 2668.6530751002247, 3014.894478693235, 3034.908952731422, 3040.4071206115036, 5255.737213139328, 5641.633293835969, 5727.878306323064, 5991.5206783457215, 6013.879722405527, 4386.978065109654, 5036.294918711384, 5460.87026059837, 5809.334904229997, 5915.467607915222, 5777.728899642072, 5914.585978981516, 5927.31835838884, 5939.0, 5973.402659733992, 4754.762261886961, 4816.638752093537, 5323.361196656426, 5711.914321285101, 5793.246877906014, 5296.947030529645, 5796.956547821433, 5801.477866991991, 5822.82531524055, 5864.912026319524, 5380.43505431924, 5702.8289151325525, 5812.338226917184, 5857.326407463196, 6046.667433291212, 3195.344954284413, 3888.1446081861945, 3920.1179734559496, 3949.1114499238593, 3965.068208970947, 5151.613628977767, 5156.143788236157, 5496.0931446310915, 5587.44125587438, 5922.763089476372, 5580.637258578179, 5660.0, 5665.405132461032, 5676.679142701566, 5777.335606405317, 4735.857924262277, 5561.610687251854, 5583.637063590853, 5630.4369563045, 5699.401562835843, 4810.59109975642, 5311.415744268204, 5498.505134537911, 5531.197976293495, 5553.9725150847225, 5460.0, 5562.694051827027, 5579.302587176603, 5635.746391412478, 5649.0, 5249.897521520524, 5474.0, 5492.835214943558, 5506.301912063141, 5526.613137080527], "value": 4080.8460088949632, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.5994992678916, 781.8311624121935, 830.5764060329237, 836.3267569606464, 838.8364268967551, 15143.469978550753, 16396.75404868925, 16413.589660258498, 16429.096399698014, 16458.83541164588, 14817.481388151416, 14901.0, 14923.925380373083, 14952.252387380615, 14976.850231497681, 704.4223736536043, 731.6817184524722, 747.2378174262262, 884.0761404332488, 907.346710368537, 894.575076838503, 895.154079394972, 896.4666023715879, 896.4666023715879, 897.0356866368647, 924.8150369926008, 942.519315149272, 943.9103285187896, 944.9763755906123, 947.3889341374809, 732.2055569706821, 924.2559739409772, 935.6865450074199, 942.9528523573828, 954.6086104697041, 701.8982247574124, 701.9964900175518, 703.644659446976, 704.4153352717233, 707.253847191213, 15039.248037598076, 15115.470958516398, 15117.39530418782, 15119.16844573548, 15119.244037798066, 13954.372053257526, 14140.858591414057, 14170.0, 14263.215523146222, 14372.856271437257, 12620.55828046023, 13172.0, 13258.801117983237, 13387.665308367272, 13422.127561708556, 4543.091381723635, 4584.633229341644, 4706.929396059062, 4824.228123500223, 4865.978144589605, 92805.07194928032, 93413.06586934111, 93482.53258733731, 93664.0, 93807.59288610745, 10187.0, 10765.677029689123, 11276.548938042386, 11316.207865449504, 11777.587784427504, 22547.88726056359, 22574.209902653656, 22596.774032259465, 22613.88693056557, 23267.06931722746, 7962.283394494523, 7998.12020677725, 8040.0, 8094.190580941977, 8165.63254653542, 4394.384786129945, 4505.189065968158, 4507.5267096955, 4519.909601808011, 4674.859754207379, 3464.7574669772875, 3562.038249672574, 3580.749347545699, 3581.8567257309937, 3590.1204204969426, 599.1851082527759, 658.3285049249738, 664.315754772585, 665.5474277491255, 668.3083009085573, 541.1936215039563, 594.304663543655, 607.1863702638485, 630.8706715123388, 646.731606383355, 8733.694320698745, 8733.912660873437, 9194.770130746785, 9270.629174833066, 9376.57805398745, 559.6474221240593, 615.6275453350659, 617.8362733875526, 636.7834936121741, 638.2723694987718, 3444.9492904663816, 4055.8174882130374, 4237.385579091077, 4515.503294637587, 4610.446746390455, 90021.54989225011, 90115.09884901198, 90122.54938725264, 138606.30696846452, 139153.30423348022, 99600.50599241017, 127494.36252818677, 129492.70507294995, 130543.0, 131357.0, 107758.0, 111159.44420277847, 111430.44284778525, 113670.86329136603, 118549.40725296318, 7514.624268786648, 9908.702738917844, 9927.503624818875, 9975.551100200364, 10066.949665251626, 54396.728016359666, 62692.79032911091, 64231.0, 65997.0, 66599.66700166468, 78931.60534197293, 89875.20249594841, 94000.58999114882, 101026.0, 102698.0, 14121.717565648834, 14316.212608306643, 14465.638359041108, 14613.634659133606, 14920.776188356966, 13263.403146858222, 14104.576862694135, 14985.850141498882, 15103.924480377527, 15446.922765386102, 13431.395587198404, 13725.588232353046, 14299.428022878968, 14301.499447519287, 14368.56894293131, 14060.156390616596, 14243.287835608386, 14292.35684394184, 14535.200563969083, 14870.702585948004, 13528.255945923069, 13639.79540306916, 13717.314134293047, 14352.784708229594, 14436.206008669624, 9495.715128546155, 10647.988441098083, 11010.119190464904, 11112.444377780917, 11959.282443053444, 11139.443027848738, 11399.771587466972, 11481.138914581607, 11631.0, 11680.0], "value": 7884.15568712709, "unit": "score"}], "name": "dromaeo_css", "value": 7354.87551069813, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 112.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 107.0, 161.0, 107.0, 107.0, 129.0, 106.0, 140.0, 111.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 186.0, 184.0, 183.0, 184.0, 182.0, 182.0, 182.0, 186.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 80.0, 81.0, 110.0, 83.0, 58.0, 58.0, 64.0, 81.0, 58.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 112.0, 128.0, 103.0, 71.0, 69.0, 98.0, 70.0, 68.0, 101.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 86.0, 88.0, 85.0, 90.0, 86.0, 88.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 93.0, 95.0, 94.0, 94.0, 96.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 84.0, 85.0, 85.0, 101.0, 132.0, 134.0, 84.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 52.0, 53.0, 53.0, 53.0, 52.0, 53.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 79.0, 78.0, 77.0, 71.0, 72.0, 72.0, 71.0, 73.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 97.0, 98.0, 85.0, 81.0, 85.0, 86.0, 83.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 141.0, 144.0, 147.0, 147.0, 147.0, 196.0, 154.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 172.0, 170.0, 171.0, 171.0, 172.0, 174.0, 173.0, 171.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 70.0, 85.0, 90.0, 89.0, 85.0, 67.0, 67.0], "value": 76.0, "unit": "score"}], "name": "kraken", "value": 1472.7000000000003, "alertThreshold": 2.0}]} 03:26:27 INFO - Return code: 0 03:26:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:26:27 INFO - # TBPL SUCCESS # 03:26:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:26:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:26:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:26:27 INFO - 2016-07-20 03:26:27.107 screenresolution[2564:25047] starting screenresolution argv=screenresolution get 03:26:27 INFO - 2016-07-20 03:26:27.110 screenresolution[2564:25047] Display 0: 1600x1200x32@60 03:26:27 INFO - 2016-07-20 03:26:27.116 screenresolution[2565:25050] starting screenresolution argv=screenresolution list 03:26:27 INFO - Available Modes on Display 0 03:26:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:26:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:26:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:26:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:26:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:26:27 INFO - Intel Iris: 03:26:27 INFO - Chipset Model: Intel Iris 03:26:27 INFO - Type: GPU 03:26:27 INFO - Bus: Built-In 03:26:27 INFO - VRAM (Dynamic, Max): 1536 MB 03:26:27 INFO - Vendor: Intel (0x8086) 03:26:27 INFO - Device ID: 0x0a2e 03:26:27 INFO - Revision ID: 0x0009 03:26:27 INFO - Displays: 03:26:27 INFO - Display: 03:26:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:26:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:26:27 INFO - Display Serial Number: 1600x1200 60 03:26:27 INFO - Main Display: Yes 03:26:27 INFO - Mirror: Off 03:26:27 INFO - Online: Yes 03:26:27 INFO - Rotation: Supported 03:26:27 INFO - Return code: 0 03:26:27 INFO - Running post-action listener: _resource_record_post_action 03:26:27 INFO - [mozharness: 2016-07-20 10:26:27.353752Z] Finished run-tests step (success) 03:26:27 INFO - Running post-run listener: _resource_record_post_run 03:26:27 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 155648; Write bytes: 702219264; Read time: 2; Write time: 1863 03:26:27 INFO - TinderboxPrint: CPU usage
53.3% 03:26:27 INFO - TinderboxPrint: I/O read bytes / time
155,648 / 2 03:26:27 INFO - TinderboxPrint: I/O write bytes / time
702,219,264 / 1,863 03:26:27 INFO - TinderboxPrint: CPU idle
2,261.7 (46.7%) 03:26:27 INFO - TinderboxPrint: CPU system
966.5 (19.9%) 03:26:27 INFO - TinderboxPrint: CPU user
1,617.4 (33.4%) 03:26:27 INFO - TinderboxPrint: Swap in / out
407,134,208 / 0 03:26:27 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 399221248; Write bytes: 393500160; Read time: 29472; Write time: 722 03:26:27 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 155648; Write bytes: 291085824; Read time: 2; Write time: 1100 03:26:27 INFO - Running post-run listener: _upload_blobber_files 03:26:27 INFO - Blob upload gear active. 03:26:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:26:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:26:27 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:26:27 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:26:28 INFO - (blobuploader) - INFO - Open directory for files ... 03:26:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:26:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:26:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:26:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:26:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:26:29 INFO - (blobuploader) - INFO - Done attempting. 03:26:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:26:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:26:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:26:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:26:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:26:30 INFO - (blobuploader) - INFO - Done attempting. 03:26:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:26:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:26:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:26:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:26:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:26:33 INFO - (blobuploader) - INFO - Done attempting. 03:26:33 INFO - (blobuploader) - INFO - Iteration through files over. 03:26:33 INFO - Return code: 0 03:26:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:26:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:26:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887926526094674cab3845c09dab5c611a8c1238e88215060e15d5aaa07dd8cc2f00162241c5f448d82a8395841c799533e1ba77bb8ed61f43396fdf97dadfed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cf0d2b30b4ccc050d0d6698caafaccab9d042f068b12dbb5d914431b50b13d10dcacf4754a40b41af8330a02a9738b36c367d032f9c6c660d319fc8f926257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0cf76db29f398d60e75d1c22037b8ac1dcb4a033604936385e0a605db1ce93c8e4f6ec2395674e8a382ad344e5d6dc90d9463a32272f8bb9793624d43fda61"} 03:26:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:26:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:26:33 INFO - Contents: 03:26:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887926526094674cab3845c09dab5c611a8c1238e88215060e15d5aaa07dd8cc2f00162241c5f448d82a8395841c799533e1ba77bb8ed61f43396fdf97dadfed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cf0d2b30b4ccc050d0d6698caafaccab9d042f068b12dbb5d914431b50b13d10dcacf4754a40b41af8330a02a9738b36c367d032f9c6c660d319fc8f926257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0cf76db29f398d60e75d1c22037b8ac1dcb4a033604936385e0a605db1ce93c8e4f6ec2395674e8a382ad344e5d6dc90d9463a32272f8bb9793624d43fda61"} 03:26:33 INFO - Running post-run listener: copy_logs_to_upload_dir 03:26:33 INFO - Copying logs to upload dir... 03:26:33 INFO - mkdir: /builds/slave/test/build/upload/logs 03:26:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.980469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-20 03:26:33.186827) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 03:26:33.190170) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QY2VLXHYOv/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887926526094674cab3845c09dab5c611a8c1238e88215060e15d5aaa07dd8cc2f00162241c5f448d82a8395841c799533e1ba77bb8ed61f43396fdf97dadfed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cf0d2b30b4ccc050d0d6698caafaccab9d042f068b12dbb5d914431b50b13d10dcacf4754a40b41af8330a02a9738b36c367d032f9c6c660d319fc8f926257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0cf76db29f398d60e75d1c22037b8ac1dcb4a033604936385e0a605db1ce93c8e4f6ec2395674e8a382ad344e5d6dc90d9463a32272f8bb9793624d43fda61"} build_url:https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/SN5pNiP_Q2aeJ1I361ZBjQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/887926526094674cab3845c09dab5c611a8c1238e88215060e15d5aaa07dd8cc2f00162241c5f448d82a8395841c799533e1ba77bb8ed61f43396fdf97dadfed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6cf0d2b30b4ccc050d0d6698caafaccab9d042f068b12dbb5d914431b50b13d10dcacf4754a40b41af8330a02a9738b36c367d032f9c6c660d319fc8f926257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb0cf76db29f398d60e75d1c22037b8ac1dcb4a033604936385e0a605db1ce93c8e4f6ec2395674e8a382ad344e5d6dc90d9463a32272f8bb9793624d43fda61"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 03:26:33.222003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 03:26:33.222335) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QY2VLXHYOv/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.XJfzefLtAA/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005540 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 03:26:33.271686) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 03:26:33.272024) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 03:26:33.272349) ========= ========= Total master_lag: 0.17 =========