builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0254 starttime: 1464182295.12 results: success (0) buildid: 20160525045846 builduid: 6759c13f5aa24c259bcb8d8cfe2dca81 revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.119948) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.120397) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.120669) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.142315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.142572) ========= 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.JD1nBaPgcW/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.f5rLn9N6No/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-05-25 06:18:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 42.1M=0s 2016-05-25 06:18:15 (42.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105174 ========= master_lag: 0.57 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.816624) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.816961) ========= 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.JD1nBaPgcW/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.f5rLn9N6No/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.025532 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.860868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:15.861191) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 242c458c4c61e94c8d12628612ef5e4e83992eaa --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 242c458c4c61e94c8d12628612ef5e4e83992eaa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JD1nBaPgcW/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.f5rLn9N6No/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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-05-25 06:18:15,936 truncating revision to first 12 chars 2016-05-25 06:18:15,936 Setting DEBUG logging. 2016-05-25 06:18:15,936 attempt 1/10 2016-05-25 06:18:15,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/242c458c4c61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 06:18:16,530 unpacking tar archive at: mozilla-central-242c458c4c61/testing/mozharness/ program finished with exit code 0 elapsedTime=0.848211 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.796497) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.796857) ========= script_repo_revision: 242c458c4c61e94c8d12628612ef5e4e83992eaa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.797292) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.797594) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 06:18:16.814656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 50 secs) (at 2016-05-25 06:18:16.814980) ========= /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.JD1nBaPgcW/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.f5rLn9N6No/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:18:16 INFO - MultiFileLogger online at 20160525 06:18:16 in /builds/slave/test 06:18:16 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 06:18:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:18:16 INFO - {'append_to_log': False, 06:18:16 INFO - 'base_work_dir': '/builds/slave/test', 06:18:16 INFO - 'blob_upload_branch': 'Firefox', 06:18:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:18:16 INFO - 'branch': 'Firefox', 06:18:16 INFO - 'buildbot_json_path': 'buildprops.json', 06:18:16 INFO - 'config_files': ('talos/mac_config.py',), 06:18:16 INFO - 'default_actions': ('clobber', 06:18:16 INFO - 'read-buildbot-config', 06:18:16 INFO - 'download-and-extract', 06:18:16 INFO - 'populate-webroot', 06:18:16 INFO - 'create-virtualenv', 06:18:16 INFO - 'install', 06:18:16 INFO - 'run-tests'), 06:18:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:18:16 INFO - 'download_minidump_stackwalk': True, 06:18:16 INFO - 'download_symbols': 'ondemand', 06:18:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:18:16 INFO - 'tooltool.py': '/tools/tooltool.py', 06:18:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:18:16 INFO - '/tools/misc-python/virtualenv.py')}, 06:18:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:18:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:18:16 INFO - 'installer_path': 'installer.exe', 06:18:16 INFO - 'log_level': 'info', 06:18:16 INFO - 'log_name': 'talos', 06:18:16 INFO - 'log_to_console': True, 06:18:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:18:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:18:16 INFO - 'opt_config_files': (), 06:18:16 INFO - 'pip_index': False, 06:18:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:16 INFO - 'cmd': ('bash', 06:18:16 INFO - '-c', 06:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:16 INFO - 'enabled': True, 06:18:16 INFO - 'halt_on_failure': False, 06:18:16 INFO - 'name': 'check_screen_resolution'},), 06:18:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:16 INFO - 'cmd': ('bash', 06:18:16 INFO - '-c', 06:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:16 INFO - 'enabled': True, 06:18:16 INFO - 'halt_on_failure': False, 06:18:16 INFO - 'name': 'check_screen_resolution'},), 06:18:16 INFO - 'run_cmd_checks_enabled': True, 06:18:16 INFO - 'sps_profile': False, 06:18:16 INFO - 'sps_profile_interval': 0, 06:18:16 INFO - 'suite': 'dromaeojs', 06:18:16 INFO - 'system_bits': '32', 06:18:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:18:16 INFO - 'title': 't-yosemite-r7-0254', 06:18:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:18:16 INFO - 'use_talos_json': True, 06:18:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:18:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:18:16 INFO - 'work_dir': 'build'} 06:18:16 INFO - ##### 06:18:16 INFO - ##### Running clobber step. 06:18:16 INFO - ##### 06:18:16 INFO - Running pre-action listener: _resource_record_pre_action 06:18:16 INFO - Running main action method: clobber 06:18:16 INFO - rmtree: /builds/slave/test/build 06:18:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:18:18 INFO - Running post-action listener: _resource_record_post_action 06:18:18 INFO - ##### 06:18:18 INFO - ##### Finished clobber step (success) 06:18:18 INFO - ##### 06:18:18 INFO - ##### 06:18:18 INFO - ##### Running read-buildbot-config step. 06:18:18 INFO - ##### 06:18:18 INFO - Running pre-action listener: _resource_record_pre_action 06:18:18 INFO - Running main action method: read_buildbot_config 06:18:18 INFO - Using buildbot properties: 06:18:18 INFO - { 06:18:18 INFO - "project": "", 06:18:18 INFO - "product": "firefox", 06:18:18 INFO - "script_repo_revision": "production", 06:18:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:18:18 INFO - "repository": "", 06:18:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:18:18 INFO - "buildid": "20160525045846", 06:18:18 INFO - "pgo_build": "False", 06:18:18 INFO - "basedir": "/builds/slave/test", 06:18:18 INFO - "buildnumber": 253, 06:18:18 INFO - "slavename": "t-yosemite-r7-0254", 06:18:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 06:18:18 INFO - "platform": "yosemite_r7", 06:18:18 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:18:18 INFO - "branch": "mozilla-central", 06:18:18 INFO - "repo_path": "mozilla-central", 06:18:18 INFO - "revision": "242c458c4c61e94c8d12628612ef5e4e83992eaa", 06:18:18 INFO - "stage_platform": "macosx64", 06:18:18 INFO - "builduid": "6759c13f5aa24c259bcb8d8cfe2dca81", 06:18:18 INFO - "slavebuilddir": "test" 06:18:18 INFO - } 06:18:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:18:18 INFO - Running post-action listener: _resource_record_post_action 06:18:18 INFO - ##### 06:18:18 INFO - ##### Finished read-buildbot-config step (success) 06:18:18 INFO - ##### 06:18:18 INFO - ##### 06:18:18 INFO - ##### Running download-and-extract step. 06:18:18 INFO - ##### 06:18:18 INFO - Running pre-action listener: _resource_record_pre_action 06:18:18 INFO - Running main action method: download_and_extract 06:18:18 INFO - mkdir: /builds/slave/test/build/tests 06:18:18 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')]} 06:18:18 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:18:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 06:18:19 INFO - Downloaded 1199 bytes. 06:18:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:18:19 INFO - Using the following test package requirements: 06:18:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:18:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:18:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:18:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'jsshell-mac.zip'], 06:18:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:18:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:18:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:18:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:18:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:18:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:18:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:18:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:19 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:18:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:18:21 INFO - Downloaded 18632937 bytes. 06:18:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:18:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:18:24 INFO - Return code: 0 06:18:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:24 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:18:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:18:29 INFO - Downloaded 18632937 bytes. 06:18:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:18:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:18:33 INFO - Return code: 0 06:18:33 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')]} 06:18:33 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:18:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:18:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:18:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:18:34 INFO - Downloaded 10840359 bytes. 06:18:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:18:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:18:35 INFO - Return code: 0 06:18:35 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')]} 06:18:35 INFO - https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:18:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:18:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 06:18:40 INFO - Downloaded 110240681 bytes. 06:18:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:40 INFO - mkdir: /builds/slave/test/properties 06:18:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:18:40 INFO - Writing to file /builds/slave/test/properties/build_url 06:18:40 INFO - Contents: 06:18:40 INFO - build_url:https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:18:41 INFO - Running post-action listener: _resource_record_post_action 06:18:41 INFO - Running post-action listener: set_extra_try_arguments 06:18:41 INFO - ##### 06:18:41 INFO - ##### Finished download-and-extract step (success) 06:18:41 INFO - ##### 06:18:41 INFO - ##### 06:18:41 INFO - ##### Running populate-webroot step. 06:18:41 INFO - ##### 06:18:41 INFO - Running pre-action listener: _resource_record_pre_action 06:18:41 INFO - Running main action method: populate_webroot 06:18:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:18:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:18:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:18:41 INFO - '--tppagecycles', 06:18:41 INFO - '7'], 06:18:41 INFO - 'tests': ['tsvgm']}}, 06:18:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:18:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:18:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:18:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:18:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:18:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['damp', 'tps']}, 06:18:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'tests': ['damp', 'tps']}, 06:18:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['dromaeo_dom']}, 06:18:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:18:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['a11yr', 06:18:41 INFO - 'ts_paint', 06:18:41 INFO - 'tpaint', 06:18:41 INFO - 'sessionrestore', 06:18:41 INFO - 'sessionrestore_no_auto_restore', 06:18:41 INFO - 'tabpaint']}, 06:18:41 INFO - 'other-e10s': {'tests': ['a11yr', 06:18:41 INFO - 'ts_paint', 06:18:41 INFO - 'tpaint', 06:18:41 INFO - 'sessionrestore', 06:18:41 INFO - 'sessionrestore_no_auto_restore', 06:18:41 INFO - 'tabpaint']}, 06:18:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:18:41 INFO - 'ts_paint', 06:18:41 INFO - 'tpaint', 06:18:41 INFO - 'sessionrestore', 06:18:41 INFO - 'sessionrestore_no_auto_restore', 06:18:41 INFO - 'tabpaint']}, 06:18:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:18:41 INFO - 'ts_paint', 06:18:41 INFO - 'tpaint', 06:18:41 INFO - 'sessionrestore', 06:18:41 INFO - 'sessionrestore_no_auto_restore', 06:18:41 INFO - 'tabpaint']}, 06:18:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['a11yr', 06:18:41 INFO - 'ts_paint', 06:18:41 INFO - 'tpaint', 06:18:41 INFO - 'sessionrestore', 06:18:41 INFO - 'sessionrestore_no_auto_restore', 06:18:41 INFO - 'tabpaint']}, 06:18:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['a11yr', 06:18:41 INFO - 'ts_paint', 06:18:41 INFO - 'tpaint', 06:18:41 INFO - 'sessionrestore', 06:18:41 INFO - 'sessionrestore_no_auto_restore', 06:18:41 INFO - 'tabpaint']}, 06:18:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['tsvgx', 06:18:41 INFO - 'tsvgr_opacity', 06:18:41 INFO - 'tart', 06:18:41 INFO - 'tscrollx', 06:18:41 INFO - 'cart']}, 06:18:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:18:41 INFO - 'tsvgr_opacity', 06:18:41 INFO - 'tart', 06:18:41 INFO - 'tscrollx', 06:18:41 INFO - 'cart']}, 06:18:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'talos_options': ['--disable-e10s'], 06:18:41 INFO - 'tests': ['tp5o']}, 06:18:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'tests': ['tp5o']}, 06:18:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'talos_options': ['--disable-e10s', 06:18:41 INFO - '--xperf_path', 06:18:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:41 INFO - 'tests': ['tp5n']}, 06:18:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:18:41 INFO - 'talos_options': ['--xperf_path', 06:18:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:18:41 INFO - 'tests': ['tp5n']}}, 06:18:41 INFO - 'talos.zip': {'path': '', 06:18:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:18:41 INFO - Running post-action listener: _resource_record_post_action 06:18:41 INFO - ##### 06:18:41 INFO - ##### Finished populate-webroot step (success) 06:18:41 INFO - ##### 06:18:41 INFO - ##### 06:18:41 INFO - ##### Running create-virtualenv step. 06:18:41 INFO - ##### 06:18:41 INFO - Running pre-action listener: _resource_record_pre_action 06:18:41 INFO - Running main action method: create_virtualenv 06:18:41 INFO - Creating virtualenv /builds/slave/test/build/venv 06:18:41 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 06:18:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:18:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:18:41 INFO - Using real prefix '/tools/python27' 06:18:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:18:42 INFO - Installing distribute.............................................................................................................................................................................................done. 06:18:45 INFO - Installing pip.................done. 06:18:45 INFO - Return code: 0 06:18:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:18:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:45 INFO - 'HOME': '/Users/cltbld', 06:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:45 INFO - 'LOGNAME': 'cltbld', 06:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:45 INFO - 'MOZ_NO_REMOTE': '1', 06:18:45 INFO - 'NO_EM_RESTART': '1', 06:18:45 INFO - 'PAGER': '/bin/cat', 06:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:45 INFO - 'PWD': '/builds/slave/test', 06:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:45 INFO - 'SHELL': '/bin/bash', 06:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:18:45 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:45 INFO - 'USER': 'cltbld', 06:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:45 INFO - 'XPC_FLAGS': '0x0', 06:18:45 INFO - 'XPC_SERVICE_NAME': '0', 06:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:45 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 06:18:45 INFO - Cleaning up... 06:18:45 INFO - Return code: 0 06:18:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:18:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:18:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:18:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:18:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:45 INFO - 'HOME': '/Users/cltbld', 06:18:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:45 INFO - 'LOGNAME': 'cltbld', 06:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:45 INFO - 'MOZ_NO_REMOTE': '1', 06:18:45 INFO - 'NO_EM_RESTART': '1', 06:18:45 INFO - 'PAGER': '/bin/cat', 06:18:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:45 INFO - 'PWD': '/builds/slave/test', 06:18:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:45 INFO - 'SHELL': '/bin/bash', 06:18:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:18:45 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:18:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:45 INFO - 'USER': 'cltbld', 06:18:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:45 INFO - 'XPC_FLAGS': '0x0', 06:18:45 INFO - 'XPC_SERVICE_NAME': '0', 06:18:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:45 INFO - Downloading/unpacking psutil>=3.1.1 06:18:45 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 06:18:45 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 06:18:45 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 06:18:45 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 06:18:45 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 06:18:45 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 06:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:18:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:48 INFO - Installing collected packages: psutil 06:18:48 INFO - Running setup.py install for psutil 06:18:48 INFO - building 'psutil._psutil_osx' extension 06:18:48 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 06:18:49 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 06:18:49 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 06:18:49 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 06:18:49 INFO - building 'psutil._psutil_posix' extension 06:18:49 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 06:18:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:18:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:18:49 INFO - ^ 06:18:49 INFO - 1 warning generated. 06:18:49 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 06:18:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:18:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:18:49 INFO - Successfully installed psutil 06:18:49 INFO - Cleaning up... 06:18:49 INFO - Return code: 0 06:18:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:18: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')]} 06:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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')]} 06:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:18: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 mozsystemmonitor==0.3 06:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:49 INFO - 'HOME': '/Users/cltbld', 06:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:49 INFO - 'LOGNAME': 'cltbld', 06:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:49 INFO - 'MOZ_NO_REMOTE': '1', 06:18:49 INFO - 'NO_EM_RESTART': '1', 06:18:49 INFO - 'PAGER': '/bin/cat', 06:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:49 INFO - 'PWD': '/builds/slave/test', 06:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:49 INFO - 'SHELL': '/bin/bash', 06:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:18:49 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:49 INFO - 'USER': 'cltbld', 06:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:49 INFO - 'XPC_FLAGS': '0x0', 06:18:49 INFO - 'XPC_SERVICE_NAME': '0', 06:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:18:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:18:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:18:52 INFO - Installing collected packages: mozsystemmonitor 06:18:52 INFO - Running setup.py install for mozsystemmonitor 06:18:52 INFO - Successfully installed mozsystemmonitor 06:18:52 INFO - Cleaning up... 06:18:52 INFO - Return code: 0 06:18:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:18:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:18:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:52 INFO - 'HOME': '/Users/cltbld', 06:18:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:52 INFO - 'LOGNAME': 'cltbld', 06:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:52 INFO - 'MOZ_NO_REMOTE': '1', 06:18:52 INFO - 'NO_EM_RESTART': '1', 06:18:52 INFO - 'PAGER': '/bin/cat', 06:18:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:52 INFO - 'PWD': '/builds/slave/test', 06:18:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:52 INFO - 'SHELL': '/bin/bash', 06:18:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:18:52 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:18:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:52 INFO - 'USER': 'cltbld', 06:18:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:52 INFO - 'XPC_FLAGS': '0x0', 06:18:52 INFO - 'XPC_SERVICE_NAME': '0', 06:18:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:53 INFO - Downloading/unpacking blobuploader==1.2.4 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:55 INFO - Downloading blobuploader-1.2.4.tar.gz 06:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:18:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:18:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18: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 06:18:56 INFO - Downloading docopt-0.6.1.tar.gz 06:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:18:57 INFO - Installing collected packages: blobuploader, requests, docopt 06:18:57 INFO - Running setup.py install for blobuploader 06:18:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:18:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:18:57 INFO - Running setup.py install for requests 06:18:57 INFO - Running setup.py install for docopt 06:18:57 INFO - Successfully installed blobuploader requests docopt 06:18:57 INFO - Cleaning up... 06:18:57 INFO - Return code: 0 06:18:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:18:57 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 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:18:57 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 06:18:57 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 06:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:18:57 INFO - 'HOME': '/Users/cltbld', 06:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:18:57 INFO - 'LOGNAME': 'cltbld', 06:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:18:57 INFO - 'MOZ_NO_REMOTE': '1', 06:18:57 INFO - 'NO_EM_RESTART': '1', 06:18:57 INFO - 'PAGER': '/bin/cat', 06:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:18:57 INFO - 'PWD': '/builds/slave/test', 06:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:18:57 INFO - 'SHELL': '/bin/bash', 06:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:18:57 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:18:57 INFO - 'USER': 'cltbld', 06:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:18:57 INFO - 'XPC_FLAGS': '0x0', 06:18:57 INFO - 'XPC_SERVICE_NAME': '0', 06:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-KxQTFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-MJpe7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-45GsBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Kgqub0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-F2di38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-NY14PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-_sX1bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:18:58 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Vla4Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-nhCTa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-KbpL9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-g3b_gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Ut7gqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-oqR62v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-fN5Ui7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Geqslu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-WuIrcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:18:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:18:59 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-E1a4p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:18:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:18:59 INFO - Running setup.py install for manifestparser 06:19:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:19:00 INFO - Running setup.py install for mozcrash 06:19:00 INFO - Running setup.py install for mozdebug 06:19:00 INFO - Running setup.py install for mozdevice 06:19:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:19:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:19:00 INFO - Running setup.py install for mozfile 06:19:00 INFO - Running setup.py install for mozhttpd 06:19:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:19:00 INFO - Running setup.py install for mozinfo 06:19:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:19:00 INFO - Running setup.py install for mozInstall 06:19:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Running setup.py install for mozleak 06:19:01 INFO - Running setup.py install for mozlog 06:19:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Running setup.py install for moznetwork 06:19:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Running setup.py install for mozprocess 06:19:01 INFO - Running setup.py install for mozprofile 06:19:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:19:01 INFO - Running setup.py install for mozrunner 06:19:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:19:02 INFO - Running setup.py install for mozscreenshot 06:19:02 INFO - Running setup.py install for moztest 06:19:02 INFO - Running setup.py install for mozversion 06:19:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:19:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:19:02 INFO - Cleaning up... 06:19:02 INFO - Return code: 0 06:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:02 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 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:19:02 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 06:19:02 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 06:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:02 INFO - 'HOME': '/Users/cltbld', 06:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:02 INFO - 'LOGNAME': 'cltbld', 06:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:02 INFO - 'MOZ_NO_REMOTE': '1', 06:19:02 INFO - 'NO_EM_RESTART': '1', 06:19:02 INFO - 'PAGER': '/bin/cat', 06:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:02 INFO - 'PWD': '/builds/slave/test', 06:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:02 INFO - 'SHELL': '/bin/bash', 06:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:19:02 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:02 INFO - 'USER': 'cltbld', 06:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:02 INFO - 'XPC_FLAGS': '0x0', 06:19:02 INFO - 'XPC_SERVICE_NAME': '0', 06:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:02 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-YUkO19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:02 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)) 06:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:02 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-foRT9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:02 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)) 06:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:02 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-3pWknR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-CzJ5IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-lTxsTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-wZoHQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Kc6l1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-p0OTHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-Z5DTfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-aWcjyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-tw6wnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:03 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)) 06:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:03 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-cbzGCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 06:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-_cGp6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:04 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)) 06:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-5zoFkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 06:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-L1_Dh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:04 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)) 06:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-nW7t5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:04 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)) 06:19:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:04 INFO - Running setup.py (path:/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/pip-TkeeOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:04 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)) 06:19:04 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)) 06:19:04 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)) 06:19:04 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)) 06:19:04 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)) 06:19:04 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)) 06:19:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:04 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 06:19:04 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 06:19:04 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 06:19:04 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 06:19:04 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 06:19:04 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 06:19:07 INFO - Downloading blessings-1.6.tar.gz 06:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:19:07 INFO - Installing collected packages: blessings 06:19:07 INFO - Running setup.py install for blessings 06:19:07 INFO - Successfully installed blessings 06:19:07 INFO - Cleaning up... 06:19:07 INFO - Return code: 0 06:19:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:07 INFO - Reading from file tmpfile_stdout 06:19:07 INFO - Current package versions: 06:19:07 INFO - blessings == 1.6 06:19:07 INFO - blobuploader == 1.2.4 06:19:07 INFO - docopt == 0.6.1 06:19:07 INFO - manifestparser == 1.1 06:19:07 INFO - mozInstall == 1.12 06:19:07 INFO - mozcrash == 0.17 06:19:07 INFO - mozdebug == 0.1 06:19:07 INFO - mozdevice == 0.48 06:19:07 INFO - mozfile == 1.2 06:19:07 INFO - mozhttpd == 0.7 06:19:07 INFO - mozinfo == 0.9 06:19:07 INFO - mozleak == 0.1 06:19:07 INFO - mozlog == 3.2 06:19:07 INFO - moznetwork == 0.27 06:19:07 INFO - mozprocess == 0.22 06:19:07 INFO - mozprofile == 0.28 06:19:07 INFO - mozrunner == 6.11 06:19:07 INFO - mozscreenshot == 0.1 06:19:07 INFO - mozsystemmonitor == 0.3 06:19:07 INFO - moztest == 0.7 06:19:07 INFO - mozversion == 1.4 06:19:07 INFO - psutil == 3.1.1 06:19:07 INFO - requests == 1.2.3 06:19:07 INFO - wsgiref == 0.1.2 06:19:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:07 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')]} 06:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:07 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')]} 06:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:07 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 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:19:07 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 06:19:07 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 06:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:07 INFO - 'HOME': '/Users/cltbld', 06:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:07 INFO - 'LOGNAME': 'cltbld', 06:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:07 INFO - 'MOZ_NO_REMOTE': '1', 06:19:07 INFO - 'NO_EM_RESTART': '1', 06:19:07 INFO - 'PAGER': '/bin/cat', 06:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:07 INFO - 'PWD': '/builds/slave/test', 06:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:07 INFO - 'SHELL': '/bin/bash', 06:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:19:07 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:07 INFO - 'USER': 'cltbld', 06:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:07 INFO - 'XPC_FLAGS': '0x0', 06:19:07 INFO - 'XPC_SERVICE_NAME': '0', 06:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 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)) 06:19:07 INFO - Cleaning up... 06:19:07 INFO - Return code: 0 06:19:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:19:07 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')]} 06:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:07 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')]} 06:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:07 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 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:19:07 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 06:19:07 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 06:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:07 INFO - 'HOME': '/Users/cltbld', 06:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:07 INFO - 'LOGNAME': 'cltbld', 06:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:07 INFO - 'MOZ_NO_REMOTE': '1', 06:19:07 INFO - 'NO_EM_RESTART': '1', 06:19:07 INFO - 'PAGER': '/bin/cat', 06:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:07 INFO - 'PWD': '/builds/slave/test', 06:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:07 INFO - 'SHELL': '/bin/bash', 06:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:19:07 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:07 INFO - 'USER': 'cltbld', 06:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:07 INFO - 'XPC_FLAGS': '0x0', 06:19:07 INFO - 'XPC_SERVICE_NAME': '0', 06:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:08 INFO - Downloading/unpacking jsonschema 06:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:19:10 INFO - Downloading/unpacking functools32 (from jsonschema) 06:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:11 INFO - Downloading functools32-3.2.3-2.tar.gz 06:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:19:11 INFO - warning: no files found matching '*.txt' 06:19:11 INFO - no previously-included directories found matching 'build' 06:19:11 INFO - no previously-included directories found matching 'dist' 06:19:11 INFO - no previously-included directories found matching '.git*' 06:19:11 INFO - Installing collected packages: jsonschema, functools32 06:19:11 INFO - Running setup.py install for functools32 06:19:11 INFO - warning: no files found matching '*.txt' 06:19:11 INFO - no previously-included directories found matching 'build' 06:19:11 INFO - no previously-included directories found matching 'dist' 06:19:11 INFO - no previously-included directories found matching '.git*' 06:19:11 INFO - Successfully installed jsonschema functools32 06:19:11 INFO - Cleaning up... 06:19:11 INFO - Return code: 0 06:19:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:11 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103520a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103526570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a52588bf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, '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.JD1nBaPgcW/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.f5rLn9N6No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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 06:19:11 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', 'flake8'] in /builds/slave/test/build 06:19:11 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 flake8 06:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:11 INFO - 'HOME': '/Users/cltbld', 06:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:11 INFO - 'LOGNAME': 'cltbld', 06:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:11 INFO - 'MOZ_NO_REMOTE': '1', 06:19:11 INFO - 'NO_EM_RESTART': '1', 06:19:11 INFO - 'PAGER': '/bin/cat', 06:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:11 INFO - 'PWD': '/builds/slave/test', 06:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:11 INFO - 'SHELL': '/bin/bash', 06:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:19:11 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:11 INFO - 'USER': 'cltbld', 06:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:11 INFO - 'XPC_FLAGS': '0x0', 06:19:11 INFO - 'XPC_SERVICE_NAME': '0', 06:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:11 INFO - Downloading/unpacking flake8 06:19: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 06:19: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 06:19: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 06:19: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 06:19: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 06:19: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 06:19:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:19:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:19:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 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 06:19:14 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 06:19:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:19:15 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 06:19:15 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 06:19:15 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 06:19:15 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 06:19:15 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 06:19:15 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 06:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:19:15 INFO - no previously-included directories found matching 'docs/_build' 06:19:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:19:15 INFO - Running setup.py install for pep8 06:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:19:15 INFO - no previously-included directories found matching 'docs/_build' 06:19:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:19:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:19:15 INFO - Cleaning up... 06:19:15 INFO - Return code: 0 06:19:15 INFO - Running post-action listener: _resource_record_post_action 06:19:15 INFO - Running post-action listener: _start_resource_monitoring 06:19:15 INFO - Starting resource monitoring. 06:19:15 INFO - ##### 06:19:15 INFO - ##### Finished create-virtualenv step (success) 06:19:15 INFO - ##### 06:19:15 INFO - ##### 06:19:15 INFO - ##### Running install step. 06:19:15 INFO - ##### 06:19:15 INFO - Running pre-action listener: _resource_record_pre_action 06:19:15 INFO - Running main action method: install 06:19:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:16 INFO - Reading from file tmpfile_stdout 06:19:16 INFO - Detecting whether we're running mozinstall >=1.0... 06:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:19:16 INFO - Reading from file tmpfile_stdout 06:19:16 INFO - Output received: 06:19:16 INFO - Usage: mozinstall [options] installer 06:19:16 INFO - Options: 06:19:16 INFO - -h, --help show this help message and exit 06:19:16 INFO - -d DEST, --destination=DEST 06:19:16 INFO - Directory to install application into. [default: 06:19:16 INFO - "/builds/slave/test"] 06:19:16 INFO - --app=APP Application being installed. [default: firefox] 06:19:16 INFO - mkdir: /builds/slave/test/build/application 06:19:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:19:49 INFO - Reading from file tmpfile_stdout 06:19:49 INFO - Output received: 06:19:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:19:49 INFO - Running post-action listener: _resource_record_post_action 06:19:49 INFO - ##### 06:19:49 INFO - ##### Finished install step (success) 06:19:49 INFO - ##### 06:19:49 INFO - ##### 06:19:49 INFO - ##### Running run-tests step. 06:19:49 INFO - ##### 06:19:49 INFO - Running pre-action listener: _resource_record_pre_action 06:19:49 INFO - Running main action method: run_tests 06:19:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:19:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:19:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:19:49 INFO - 2016-05-25 06:19:49.829 screenresolution[1647:8183] starting screenresolution argv=screenresolution get 06:19:49 INFO - 2016-05-25 06:19:49.831 screenresolution[1647:8183] Display 0: 1600x1200x32@60 06:19:49 INFO - 2016-05-25 06:19:49.837 screenresolution[1648:8185] starting screenresolution argv=screenresolution list 06:19:49 INFO - Available Modes on Display 0 06:19:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:19:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:19:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:19:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:19:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:19:50 INFO - Intel Iris: 06:19:50 INFO - Chipset Model: Intel Iris 06:19:50 INFO - Type: GPU 06:19:50 INFO - Bus: Built-In 06:19:50 INFO - VRAM (Dynamic, Max): 1536 MB 06:19:50 INFO - Vendor: Intel (0x8086) 06:19:50 INFO - Device ID: 0x0a2e 06:19:50 INFO - Revision ID: 0x0009 06:19:50 INFO - Displays: 06:19:50 INFO - Display: 06:19:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:19:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:19:50 INFO - Display Serial Number: 1600x1200 60 06:19:50 INFO - Main Display: Yes 06:19:50 INFO - Mirror: Off 06:19:50 INFO - Online: Yes 06:19:50 INFO - Rotation: Supported 06:19:50 INFO - Return code: 0 06:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:19:50 INFO - Python 2.7.3 06:19:50 INFO - Return code: 0 06:19:50 INFO - grabbing minidump binary from tooltool 06:19: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')]} 06:19: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 0x1033c4d30>, '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 0x1031e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1033bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:19: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 06:19: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 06:19: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 06:19:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:19:50 INFO - Return code: 0 06:19:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:19:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:19:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:19:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:19:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:19:51 INFO - Return code: 0 06:19:51 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/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 06:19:51 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/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0254 --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 06:19:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JD1nBaPgcW/Render', 06:19:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:51 INFO - 'HOME': '/Users/cltbld', 06:19:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:51 INFO - 'LOGNAME': 'cltbld', 06:19:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:19:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:51 INFO - 'MOZ_NO_REMOTE': '1', 06:19:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:19:51 INFO - 'NO_EM_RESTART': '1', 06:19:51 INFO - 'PAGER': '/bin/cat', 06:19:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:51 INFO - 'PWD': '/builds/slave/test', 06:19:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:19:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:51 INFO - 'SHELL': '/bin/bash', 06:19:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f5rLn9N6No/Listeners', 06:19:51 INFO - 'TMPDIR': '/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/', 06:19:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:51 INFO - 'USER': 'cltbld', 06:19:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:51 INFO - 'XPC_FLAGS': '0x0', 06:19:51 INFO - 'XPC_SERVICE_NAME': '0', 06:19:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:51 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/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0254', '--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 06:19:51 INFO - mozversion application_buildid: 20160525045846 06:19:51 INFO - mozversion application_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 06:19:51 INFO - mozversion application_display_name: Nightly 06:19:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:19:51 INFO - mozversion application_name: Firefox 06:19:51 INFO - mozversion application_remotingname: firefox 06:19:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:19:51 INFO - mozversion application_vendor: Mozilla 06:19:51 INFO - mozversion application_version: 49.0a1 06:19:51 INFO - mozversion platform_buildid: 20160525045846 06:19:51 INFO - mozversion platform_changeset: 242c458c4c61e94c8d12628612ef5e4e83992eaa 06:19:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:19:51 INFO - mozversion platform_version: 49.0a1 06:19:51 INFO - using testdate: 1464182391 06:19:51 INFO - actual date: 1464182391 06:19:51 INFO - starting webserver on 'localhost:49270' 06:19:51 INFO - SUITE-START | Running 2 tests 06:19:51 INFO - TEST-START | dromaeo_css 06:19:51 INFO - operating with platform_type : mac_ 06:19:51 INFO - Initialising browser for dromaeo_css test... 06:19:51 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpmJc1i0/profile http://localhost:49270/getInfo.html) 06:19:53 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 06:19:53 INFO - PROCESS | 1666 | colorDepth:24 06:19:53 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 06:19:53 INFO - PROCESS | 1666 | __metrics 06:19:54 INFO - PROCESS | 1666 | 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" 06:19:54 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:19:54 INFO - TEST-INFO | 1666: exit 0 06:19:54 INFO - Browser initialized. 06:19:54 INFO - Running cycle 1/1 for dromaeo_css test... 06:19:54 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpmJc1i0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:20:06 INFO - PROCESS | 1669 | RSS: Main: 239185920 06:20:06 INFO - PROCESS | 1669 | 06:20:58 INFO - PROCESS | 1669 | 1464182458304 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] 06:23:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 06:23:09 INFO - PROCESS | 1669 | RSS: Main: 360771584 06:23:09 INFO - PROCESS | 1669 | 06:26:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 06:26:13 INFO - PROCESS | 1669 | RSS: Main: 374378496 06:26:13 INFO - PROCESS | 1669 | 06:29:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 06:29:15 INFO - PROCESS | 1669 | RSS: Main: 371056640 06:29:15 INFO - PROCESS | 1669 | 06:32:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 06:32:18 INFO - PROCESS | 1669 | RSS: Main: 367620096 06:32:18 INFO - PROCESS | 1669 | 06:35:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 06:35:22 INFO - PROCESS | 1669 | RSS: Main: 339644416 06:35:22 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 06:38:25 INFO - PROCESS | 1669 | RSS: Main: 374292480 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | __start_tp_report 06:38:25 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 06:38:25 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 06:38:25 INFO - PROCESS | 1669 | |i|pagename|runs| 06:38:25 INFO - PROCESS | 1669 | |0;dojo.html;3400.319936012798;3408.4205685033544;3426.914327141822;3442.7073698735608;3455.222574920643;5538.7784488620455;5556.555475561945;5625.099984002561;5642.238297829792;5680.545556355487;5544.6395984261;5563.332400111992;5573.637713548616;5593.105103183491;5727.11229759388;5034.622403319748;5096.566791822681;5136.5890728741615;5139.331886854704;5290.814821481249;5234.790608375661;5270.868228294305;5295.338082739658;5302.946970530304;5337.012652659251;5042.873928151789;5113.923291150636;5127.102757017507;5141.922871156936;5161.761177317454;4474.105178964221;4914.385701787277;5123.590112790967;5130.025295193938;5146.794128234904;5095.184770436712;5165.7158856262895;5169;5180.6373553851245;5203.959208158383;4406.361077643724;5524.14375771752;5526.281583394173;5527.695976721278;5602.663840169603;6184.536159787989;6185.783497577563;6186.288576813634;6209.254889413254;6218.31598524162;5226.869328266824;6211.47202487789;6257.061440783873;6278.748850046039;6291.552942823177;5611.859703507404;5674.943250567524;5720.1991721159075;5724.627899186539;5781.971090144564;6282.780102696383;6294.464970477561;6296.244450665858;6300.716467758962;6309.747610095545;6100.89988024788;6106.809172211376;6111.294303386368;6114.367021223701;6132.601380910227;5985.551083668743;6130.826205142237;6143.385661433821;6180.412860778219;6208.596441231305;6122;6167.259928808572;6169.475594574512;6182.783602573978;6188;6201.875962480725;6203.875922481525;6219.968900155471;6230.937690623035;6248.687565621701;4689.6717229793585;4751.287306903992;4751.976240118777;4859.198232291632;4947.975260123676;4330.133973205308;4341;4387.341898715218;4412.536683648235;4456.665750068693;5560.7219639017885;5575.330960284792;5579.163125531121;5585.636933599303;5587.022271102541;6030.638161710312;6111.755529778759;6131.560175786049;6230.750769969241;6236.03341482071;5595.804146854841;5609.354924183771;5615.326160860723;5624.437556244343;5675.858103547362;5268.789248430096;5326.586523346848;5336.946630533645;5343.759530821122;5353.277307563507;6200;6229.719662615284;6248.156498872594;6266.780662676784;6271.937280627227;6223;6232.781852635318;6242.875142497215;6249.375062493714;6250.124982502544;6270.12218289449;6270.278917924497;6281.371862813682;6286.65423401699;6289.874202515833;5902.527797776082;6046.153538504701;6068.939310606837;6105.694715264308;6136.447719705248;6010.602850635168;6166.445019948226;6167.528252882646;6189.6286222826775;6204.689765511796;5978.342382338026;5985.610935289193;5989.401059893976;5991;6001.5798894077;5775.306963164448;5884.264466941632;5932.49573786233;5948.494377977747;6011.128386384037;5808.157817116579;5819.476247137609;5822.388649191774;5832.854178645568;5881.911771323519;5797.159411885169;6025.698715064317;6045.848853778691;6046.365131661112;6072.72672729737;4996.975619997965;5789.305283366023;5861.6189947653265;5865.384134665798;5897.67562784051 06:38:25 INFO - PROCESS | 1669 | |1;ext.html;504.8636868045628;515.1294312611686;517.8757098296408;550.1774846604327;558.7876606889382;30800.845995770018;31414.842925785368;41923.58076419239;42987;43158;44505.219791208394;44912.65262042136;44988.550114498845;45106;46033.30950035752;9545.331826772128;9763.511824408786;9765.12113909748;9777;9786.3638863474;9544;9616.374935629161;9677.564509597085;9702.369345992487;9716.61133554657;9655.710328690115;9683.90316096837;9711.223102151846;9725.367851089692;9745.658901938434;9646.951765241163;9671.516424178762;9682.031796820262;9691.66079187232;9700.369475984075;9577.856332155023;9690.95154524223;9701.66044188457;9702;9704.417734935927;40210.79894600508;40329;40635.78092657225;40734.777956661346;40835.979100522425;2627.5533159362953;3184.7452203823636;3383.1880348716377;3468.0982944434377;3472.7048200902955;28521;28934.131976040753;29154.833806647708;29247.85376073106;29396.70603293961;2723.237493501823;2729.686086100104;2738.5344491436504;2757.806953513255;2825.22306365749;517844;518719;519015.40492297674;519671.40164299315;521329;59418.702906486054;59570.70214648986;59953.400465995655;60259.096113558335;60354;54320;55296.447035529935;61599.6920015406;63038;63391;15817.920910395604;16608.169591520375;16610.916945415196;16622.418215362646;16626;12862;12957.898578620923;12984.091113622144;13004.414801333962;13004.609861704164;13694.93152534231;15440.536783896501;15846.207689615474;15854.762178567331;15879.603009924844;5519.9448005520235;6448.806535803933;6595.307492713292;6603.8349041273395;6616.305287944697;843.7806170395742;897.3539051882634;900.7883147460342;975.8243516167158;982.577491678585;24403.26790196297;24854;29482;29720.55419168714;30025.549616755765;1076.3488089705772;1102.8014957307762;1145.788029214598;1146.7935771561033;1170.607846371475;13007.869921300666;15032.473863414729;15096.924515377354;15112.697746045018;15411.15238661862;562692;611921;618612;625434;637170;59977;60067.69966150229;60205;60290.69854650786;60335.69832150811;1392.5822253323963;1410.8095407120113;1448.7319845828345;1449.2029383839058;1451.3759083594157;25912.352191195372;28753;28760;29003.419931600827;29806.403871922874;50288.7427814293;51358.48641513687;51577.48422515727;51731.482685174175;51855.740721296155;564926;567902;568523;568684;569886;33027;39042;39536.60463395444;39679;41011.58988410197;36906.81546592357;41681;45714.77142614266;48644.756776215894;48792.756036219595;37795.81102094472;37961.4305785419;38052.42921356126;38161.80919095387;38578.6142138575;36917;37068.814655926544;37971.43042854415;38113.2377352457;38130.237395252494;36260;36917;37001.259974800894;37405.438918415704;38148.61851381451;32915.83542082274;33349.33301334008;33353.83323083369;33490.82782102615;33577.49633755544;24098;24220;24343.87828060919;25105.99576016939;25133.120340787984 06:38:25 INFO - PROCESS | 1669 | |2;jquery.html;377.19468933826283;380.35719633818854;385.3756913799645;396.4034128636402;402.840877853248;19377.806221937775;20532.486687832792;20577.38267851967;20588;21247.468813279676;20438;20844.270450534234;20901;21390.679139812877;21529.67705484419;5250.9474905250845;5906.734196961147;6026.5178785697035;6070.5447091468095;6078.39216078393;5909.113632955048;6001.699915004232;6024.337322894478;6027.758889644409;6033.818985430444;5630.662160270397;5988.431099045583;5989.850253743648;5996.340402555716;6011.218541589587;5915.556333275;5991.520678345743;5992.131140984533;6070.362611925772;6643.202815662103;5729.398413166641;5830.096335068071;5845.824625261249;5855.4144585541535;5857.062869940789;13376.130551514121;20445.2844150454;21501.03245353963;25586.744132558808;26106.477870442486;1852.2498388152685;2004.2984955265686;2021.444102871722;2060.072967164782;2227.487677834091;9488;9810.21518278536;9908.90091099087;9972.750681233027;10114.54484548197;1704.318272690921;1711.5036639374566;1748.7551742756025;1775.7691500104904;1892.4984879007075;110461.23846903811;112220.43889780498;113526;120953.39523302327;122841;11361.431928403546;11424;11567.595134170264;11723.472443740053;11880.287182769061;14837.183954882585;14935.178565178801;14964.251787410803;14970.026948248329;14980.250987450583;8541.444806087584;8620.52587107715;8739.912600874037;8852.645894164161;8872.73381798547;8629.827403451896;8647.437916535406;8660.480371177624;8727.389082764272;8759.605817738217;3655.707543396522;4560.657950653715;4594.19601569724;4644.814207431732;4654.37165982588;1855.925762969493;1900.4868685454849;1919.7984211657854;1923.519120219945;1968.2323893681476;717.1107826295356;908.2053203446984;921.5208091792231;963.3978763272954;1022.4826596507988;18487.613428992878;19518.5120371989;19692;20525.739649406776;20598.70566297375;916.3356566489375;922.1239822168966;950.5152372290182;962.7208109648263;978.4814048554277;8173.509589424653;10844.620438284623;10856.131509479223;10879.456027198608;10920.617778377718;458654;559164;573257;576459;578469;132707;133987;135156.3242183783;136495;138447.30776346053;990.6730778843108;991.9950400248097;998.5257002923553;1000.9699709008742;1011.0748664971616;13569.86430135686;17447;17965.73051404204;18257.178426971084;18299.81700182981;37875.81062094672;50633.49366506435;51709;53816.730916345165;54109.45890541044;71324;73553;74592.25407745854;75180;76992.23007770075;6937.132858392701;7423.92576074254;7453.4782565222085;7483.775486735407;7591.848163036598;12030.699232519257;12519.123661343983;12713.300768457453;13015.544455943997;13066.216027038408;10819.837702434626;10962.397068161328;10995.395253260827;11128.443577820915;11374.829377559177;10728.73178170552;10769;10817.45912704346;11243.718907027389;11430.771384572428;10942.781144376908;11094.223404361617;11266.816984216539;11270.703869055164;11299.209055366375;6854.862902742017;6996.300369962963;7312.707491700273;7458.105027396748;7749.070111586646;9357.262311858985;10793.838092428463;11066.667999959693;11089.722756931142;11092.722681933015 06:38:25 INFO - PROCESS | 1669 | |3;mootools.html;1384.5915454940791;1539.3303912797933;1546.512848452737;1575.787268718723;1944.4944314478232;7847.372210223182;8746.650133994632;8933.731988040354;8967.596458159374;8969.19277265046;8671.523066231355;8746.344024198179;8910.064443233448;8918.91081089189;8961.148690874357;2358.1864256831363;2391.497785465054;2392.449736560592;2396.4488167721347;2408.662787209792;2013.3154727392684;2347.8239132065164;2372.4424760181346;2392.138830021191;2394.844335118212;2351.0595761695286;2361.3624321433203;2365.7161140663143;2384.3443053160345;2385.654080158376;2184.530325979922;2212.126210146991;2214.092222188911;2218.0462401167492;2237.8433509654333;2320.3619004773655;2358.4575547624145;2361.315218586608;2362.14962613459;2365.940851478727;8600.5699715014;8954.597043133075;8987.236084932789;8987.730368088967;9005.14451127142;5213.478652134794;5428.4028756836715;5431.293931788881;5432.511074003319;5442.6462279951675;3451.775634583744;3528.1356067763563;3593.5148754918273;3610.3681855675413;3613.7109031277437;1021.0402221911379;1030.5156576409076;1035.1977217656301;1036.8392899100577;1039.1946241662697;11684.123690723145;11781.46983385741;11897.762044759056;11917.404129793476;11932.582359617458;4507.752073635916;4543.591076803037;4656.906861862812;4661.86014419568;4696;7980.4812687175145;9138.451692898447;9217.95391023054;9266.027067157986;9284;5249.632525723239;5268.262443257947;5278.392984806719;5280.1815718563685;5280.867978300496;4408.53710360414;4522.3668686383935;4693;4796.904061918742;4939.086269040239;3461.5846098468346;3997.560268370477;3998.100427403811;4084.346504559256;4122.319817230124;1281.6859869332072;1299.662087857164;1300.473308310136;1310.502009236503;1313.7832257677376;793.2583034862422;881.2773525708985;882.896379525593;924.9825192288429;941.8163458125599;5523.088690366125;5655.660660360391;5823.5923485355615;5972.313183983897;5972.552058595623;877.6664867350494;909.077286554148;922.6309476209569;928.6981730937472;932.6409332407072;3861.5559210690567;3867.361885288952;3967.2263908538134;4029.6574791142493;4075.4294398784195;9887.80224395508;10061;10075.244356673278;10270.127039201752;10294.17646588271;10017.799644007078;10080.495975201211;10231.13035391985;10233.590656373663;10237.897621023843;7760.495567788075;8147.714829981041;8201.22088401601;8217.137200593972;8305.044919834296;5779.219805326227;5803.709814509173;5864.853378665567;5993.640381577119;5996.37038110992;9744.85382719274;10067.1442927352;10073.798524029626;10095.192384609361;10098.444585547866;9596.424214547149;9784.951075244579;9907.356021858555;9921.851172232278;9955.352902061342;6225;6923.376896079176;7113.359797618239;7529.284717951786;7675.155732869492;8232.300254478198;8322.6670933162;8430.36772242072;8431.36764742659;8436.409451338348;7272.854542909006;7337.59643219628;7376.852462950604;7498.100227972678;7531.661075251517;7309.853802924018;7317.634118294171;7347.485676002842;7406.481546291708;7423.665935033045;6968.477364197807;7005.404540614105;7014.789556313319;7043.647817608997;7050.189228238613;4683.110209060268;4771.117343291502;4851.102546028996;4963.404391473046;5028.698278103326;4810.59109975642;4910.435299940553;4913.508649135058;4923.803047878188;5183.740812959412 06:38:25 INFO - PROCESS | 1669 | |4;prototype.html;310.13936326693425;312.2038801057303;318.5222166749876;319.9552062711221;322.7418065547561;5334.466553344669;5344.85085706573;5345.514335833836;5364.865878353038;5374.274472946151;4839.830605928793;5253.684778913268;5263.394709608397;5281.524662780349;5294.92057619136;3647.777994371882;3761.4169803680475;3885.8834234972996;3997.6402123808853;4058.431819545266;3976.324024915771;4044;4053.6757059435176;4089.447924530195;4100;3954.960450395488;3965.6827453803626;4020.376841589544;4021.8190181441905;4023.390643742497;3795.443868014101;3860.343741563941;3897.512810898638;4045;4051.1897620476025;2936.7797415193945;3155.727875929327;3530.6469353064745;3539.1152211947015;3543.326767914088;4145.419641250227;4694.990577025956;4815.41251767432;4829.492903245144;5136.922946155811;4089.9591004089875;4297.527272000078;4580.88547786308;4608.6082682971655;4639.025804581042;5475.315585551806;5540.063766203918;5540.750666220029;5544.390117087117;5581.2186293918885;3385.712214461774;3840.327942610032;3854.9614503855164;3858;3858.2669292834553;4978.975105124451;5486.862828429281;5562.972185139089;5805.303363596396;5905.616134951214;3841.0205397623427;3875.302445559826;3889.5138107736534;3895.766254024711;3896.298666240047;3476.66971637694;3498.5626796650417;3815.256025075084;3930.4890364252465;3948;4406.889827754332;4608.700434471749;4617.607503362185;4623.474253496405;4634.467036290802;5398.352197736218;5599.160125981135;5607.80372686954;5638.605297629127;5679.403662615448;1322;1339.55125033115;1342.0471465259775;1343.361903096041;1346.9730605387838;677.3565113142535;805.3476683886071;805.7663277649416;813.6663967773185;813.943023988327;4094.856680016245;4544.318352247189;4886.462489126193;5216.40011398694;5232.901090770904;4901.534354236342;5751.712414379264;5783.161441590946;5797;5799.43415277881;4490.550944905483;4623.86128416148;4803.903921921542;5119.206522988943;5198.92201616976;2591.9870400647874;2683.490136873988;2984;2994.9101526954228;2997.5503674449005;4726.976365118221;5473.972630136824;5960.76156953717;5974.3726908674835;6059.0608455689435;5430.15832545956;5552.473069905727;5853.473187413153;5879.706014699248;5963.940360596339;4910.48439913811;5996.400359963969;5999.940000600025;6015.909761353583;6036.3360030396625;4928.490431255361;5086.262491938574;5472.644278121909;5762.481376676119;5819.447152520504;5627.874425114912;5705.857353566194;5749.770009199752;5759.625624334405;5778.335491418541;5376.688024149496;5932.911006334822;5978.521718262616;5982.252218472691;6056.757729690763;3223.2264256577782;3579.82100894949;3583.4053846039305;3817.167759475523;3870.70969677281;4809.78355973989;5337.546308563816;5580;5700.144978253295;5720.513756330759;5126.333576635163;5502.284702988586;5546.500814926676;5566.666000040011;5586.636868603528;5014.9498505015945;5070.138076527073;5556.944180605672;5568.276124103841;5599.720013999202;4589.541045895384;4653.697509661861;5191.714455704972;5434.157705555645;5462.306684927685;5387.622866399314;5434.538064264424;5453.481919217668;5592.552595792408;5605.971970140124;5224.425313215619;5409.729513524387;5416.674999500043;5457.808976685797;5472.960137573848 06:38:25 INFO - PROCESS | 1669 | |5;yui.html;565.3357305166429;625.2372106030643;722.5772922840139;729.4784229276066;730.218666027351;12117.515299388015;14535.491257805965;14981;15258.923705381472;15270.923645381772;13814.102083364574;13980.300984950763;14091.154530728163;14179.432822687106;14180.149191048544;720.7009091227135;744.8770952792783;907.2560500389667;925.1534845616287;930.0652843891879;878.1789027259501;886.6497733395305;910.7723069232692;923.8845144356956;926.9351145419823;928.206383542072;958.290864760076;964.9915837949325;966.7824739433642;968.8207681578896;761.9352355049799;928.7074571509986;933.1881263300904;964.2478866484154;966.0291407135853;709.0534136927157;717.7487879242276;719.9992011104569;721.4408833154347;722.1803253307481;14249.002569820117;14399.928000360036;14610;14655;14666.56000319992;10994.680638323453;11341.943290283496;11561.479733412016;11639.47622356996;12320.137590368679;9685.70942871715;10189.59241630334;10717.678469645845;10917.563297468092;11346.943265283704;3347.2468694543777;3376.814275214887;3449.4653328733834;3515.226650136966;3581.4985901973746;80026.9973002695;80078.59960700218;80981;84136.57931710302;84539.57730211371;8976.68581599654;9149.588268527932;9216.677416290398;9284.442933423881;9456.90543094574;20223.696644550342;20579.176832926514;20597.382078537667;20613.58772824535;20619;7201;7228.31331023552;7266.382357499671;7355.926440735631;7438.5536867788105;3897.162110146332;3926.784026878549;3936.1340505088833;3981.2833689936087;4040.2929487340184;3431.24512607226;3537.5754909411035;3542.4863394807608;3543.36219480496;3548.485469606944;521.9765110570033;620.6865532905854;630.9022101574263;636.961782293064;645.4771634975684;489.19527377463874;509.36075225591645;525.4167873660234;543.4918351341429;595.8154357766357;8167.877481837777;8224.506529608243;8236.217559331853;8304.667813287522;8383.455075420079;549.2804426201689;563.2452513631753;606.2482521673181;631.2015300644688;637.9330170332048;3674.9816250918575;3846.3845784674318;3869.0520822398694;3869.1294458746556;3993.0815912339745;86043.56978215194;88227;88389.25469844439;88577.55711221402;89497;60282;74078.25921740821;74343.25656743364;75359.62320188365;76549;69226.65386673034;69241.65379173071;69308.3069169312;69551.65224173947;69623.95564066543;7603.923960760321;7715.845683086419;7738.41961852852;7797.259260370256;7868.8819667706175;34195.658043419244;44495.8822185361;44798.328025079;44955.77522112369;45369.54630453653;58214.253572392896;58752.4124758764;60395.39604604073;63062.05406918808;63090.68454657881;9855.950720246354;10136.239782016526;10270.743231418975;10368.948155259175;10394;11459.828102578635;11468.139889508153;11927.76144477123;12049.759004820029;12291.815622765844;8740.562971851508;11488.48301826403;11510.48202830858;11527.76944461123;11896.940515297369;11321.943390283326;11385.601503947322;11396.031337336088;11700;11740.295582265091;8851.380403371702;11221.719457013314;11398.20212585111;11506.597269095542;11536.307821530734;7286.6720997556295;9312.394694344846;9387.389819661701;9439.669611563837;9772.951135244279;9246.214071803972;9876.111149996535;9882.357646752938;9899.950500247453;9916.900830991886 06:38:25 INFO - PROCESS | 1669 | __end_tp_report 06:38:25 INFO - PROCESS | 1669 | __start_cc_report 06:38:25 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1255 06:38:25 INFO - PROCESS | 1669 | __end_cc_report 06:38:25 INFO - PROCESS | 1669 | __startTimestamp1464183505582__endTimestamp 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | ------- Summary: start ------- 06:38:25 INFO - PROCESS | 1669 | Number of tests: 6 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5658.62 Median:5822.39 stddev:642.30 (11.0%) stddev-sans-first:619.36 06:38:25 INFO - PROCESS | 1669 | Values: 3400.3 3408.4 3426.9 3442.7 3455.2 5538.8 5556.6 5625.1 5642.2 5680.5 5544.6 5563.3 5573.6 5593.1 5727.1 5034.6 5096.6 5136.6 5139.3 5290.8 5234.8 5270.9 5295.3 5302.9 5337.0 5042.9 5113.9 5127.1 5141.9 5161.8 4474.1 4914.4 5123.6 5130.0 5146.8 5095.2 5165.7 5169.0 5180.6 5204.0 4406.4 5524.1 5526.3 5527.7 5602.7 6184.5 6185.8 6186.3 6209.3 6218.3 5226.9 6211.5 6257.1 6278.7 6291.6 5611.9 5674.9 5720.2 5724.6 5782.0 6282.8 6294.5 6296.2 6300.7 6309.7 6100.9 6106.8 6111.3 6114.4 6132.6 5985.6 6130.8 6143.4 6180.4 6208.6 6122.0 6167.3 6169.5 6182.8 6188.0 6201.9 6203.9 6220.0 6230.9 6248.7 4689.7 4751.3 4752.0 4859.2 4948.0 4330.1 4341.0 4387.3 4412.5 4456.7 5560.7 5575.3 5579.2 5585.6 5587.0 6030.6 6111.8 6131.6 6230.8 6236.0 5595.8 5609.4 5615.3 5624.4 5675.9 5268.8 5326.6 5336.9 5343.8 5353.3 6200.0 6229.7 6248.2 6266.8 6271.9 6223.0 6232.8 6242.9 6249.4 6250.1 6270.1 6270.3 6281.4 6286.7 6289.9 5902.5 6046.2 6068.9 6105.7 6136.4 6010.6 6166.4 6167.5 6189.6 6204.7 5978.3 5985.6 5989.4 5991.0 6001.6 5775.3 5884.3 5932.5 5948.5 6011.1 5808.2 5819.5 5822.4 5832.9 5881.9 5797.2 6025.7 6045.8 6046.4 6072.7 4997.0 5789.3 5861.6 5865.4 5897.7 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:69895.97 Median:40785.38 stddev:151571.41 (371.6%) stddev-sans-first:151907.06 06:38:25 INFO - PROCESS | 1669 | Values: 504.9 515.1 517.9 550.2 558.8 30800.8 31414.8 41923.6 42987.0 43158.0 44505.2 44912.7 44988.6 45106.0 46033.3 9545.3 9763.5 9765.1 9777.0 9786.4 9544.0 9616.4 9677.6 9702.4 9716.6 9655.7 9683.9 9711.2 9725.4 9745.7 9647.0 9671.5 9682.0 9691.7 9700.4 9577.9 9691.0 9701.7 9702.0 9704.4 40210.8 40329.0 40635.8 40734.8 40836.0 2627.6 3184.7 3383.2 3468.1 3472.7 28521.0 28934.1 29154.8 29247.9 29396.7 2723.2 2729.7 2738.5 2757.8 2825.2 517844.0 518719.0 519015.4 519671.4 521329.0 59418.7 59570.7 59953.4 60259.1 60354.0 54320.0 55296.4 61599.7 63038.0 63391.0 15817.9 16608.2 16610.9 16622.4 16626.0 12862.0 12957.9 12984.1 13004.4 13004.6 13694.9 15440.5 15846.2 15854.8 15879.6 5519.9 6448.8 6595.3 6603.8 6616.3 843.8 897.4 900.8 975.8 982.6 24403.3 24854.0 29482.0 29720.6 30025.5 1076.3 1102.8 1145.8 1146.8 1170.6 13007.9 15032.5 15096.9 15112.7 15411.2 562692.0 611921.0 618612.0 625434.0 637170.0 59977.0 60067.7 60205.0 60290.7 60335.7 1392.6 1410.8 1448.7 1449.2 1451.4 25912.4 28753.0 28760.0 29003.4 29806.4 50288.7 51358.5 51577.5 51731.5 51855.7 564926.0 567902.0 568523.0 568684.0 569886.0 33027.0 39042.0 39536.6 39679.0 41011.6 36906.8 41681.0 45714.8 48644.8 48792.8 37795.8 37961.4 38052.4 38161.8 38578.6 36917.0 37068.8 37971.4 38113.2 38130.2 36260.0 36917.0 37001.3 37405.4 38148.6 32915.8 33349.3 33353.8 33490.8 33577.5 24098.0 24220.0 24343.9 25106.0 25133.1 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:33409.75 Median:25846.61 stddev:92614.13 (358.3%) stddev-sans-first:92840.72 06:38:25 INFO - PROCESS | 1669 | Values: 377.2 380.4 385.4 396.4 402.8 19377.8 20532.5 20577.4 20588.0 21247.5 20438.0 20844.3 20901.0 21390.7 21529.7 5250.9 5906.7 6026.5 6070.5 6078.4 5909.1 6001.7 6024.3 6027.8 6033.8 5630.7 5988.4 5989.9 5996.3 6011.2 5915.6 5991.5 5992.1 6070.4 6643.2 5729.4 5830.1 5845.8 5855.4 5857.1 13376.1 20445.3 21501.0 25586.7 26106.5 1852.2 2004.3 2021.4 2060.1 2227.5 9488.0 9810.2 9908.9 9972.8 10114.5 1704.3 1711.5 1748.8 1775.8 1892.5 110461.2 112220.4 113526.0 120953.4 122841.0 11361.4 11424.0 11567.6 11723.5 11880.3 14837.2 14935.2 14964.3 14970.0 14980.3 8541.4 8620.5 8739.9 8852.6 8872.7 8629.8 8647.4 8660.5 8727.4 8759.6 3655.7 4560.7 4594.2 4644.8 4654.4 1855.9 1900.5 1919.8 1923.5 1968.2 717.1 908.2 921.5 963.4 1022.5 18487.6 19518.5 19692.0 20525.7 20598.7 916.3 922.1 950.5 962.7 978.5 8173.5 10844.6 10856.1 10879.5 10920.6 458654.0 559164.0 573257.0 576459.0 578469.0 132707.0 133987.0 135156.3 136495.0 138447.3 990.7 992.0 998.5 1001.0 1011.1 13569.9 17447.0 17965.7 18257.2 18299.8 37875.8 50633.5 51709.0 53816.7 54109.5 71324.0 73553.0 74592.3 75180.0 76992.2 6937.1 7423.9 7453.5 7483.8 7591.8 12030.7 12519.1 12713.3 13015.5 13066.2 10819.8 10962.4 10995.4 11128.4 11374.8 10728.7 10769.0 10817.5 11243.7 11430.8 10942.8 11094.2 11266.8 11270.7 11299.2 6854.9 6996.3 7312.7 7458.1 7749.1 9357.3 10793.8 11066.7 11089.7 11092.7 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5639.01 Median:4996.05 stddev:3133.85 (62.7%) stddev-sans-first:3126.33 06:38:25 INFO - PROCESS | 1669 | Values: 1384.6 1539.3 1546.5 1575.8 1944.5 7847.4 8746.7 8933.7 8967.6 8969.2 8671.5 8746.3 8910.1 8918.9 8961.1 2358.2 2391.5 2392.4 2396.4 2408.7 2013.3 2347.8 2372.4 2392.1 2394.8 2351.1 2361.4 2365.7 2384.3 2385.7 2184.5 2212.1 2214.1 2218.0 2237.8 2320.4 2358.5 2361.3 2362.1 2365.9 8600.6 8954.6 8987.2 8987.7 9005.1 5213.5 5428.4 5431.3 5432.5 5442.6 3451.8 3528.1 3593.5 3610.4 3613.7 1021.0 1030.5 1035.2 1036.8 1039.2 11684.1 11781.5 11897.8 11917.4 11932.6 4507.8 4543.6 4656.9 4661.9 4696.0 7980.5 9138.5 9218.0 9266.0 9284.0 5249.6 5268.3 5278.4 5280.2 5280.9 4408.5 4522.4 4693.0 4796.9 4939.1 3461.6 3997.6 3998.1 4084.3 4122.3 1281.7 1299.7 1300.5 1310.5 1313.8 793.3 881.3 882.9 925.0 941.8 5523.1 5655.7 5823.6 5972.3 5972.6 877.7 909.1 922.6 928.7 932.6 3861.6 3867.4 3967.2 4029.7 4075.4 9887.8 10061.0 10075.2 10270.1 10294.2 10017.8 10080.5 10231.1 10233.6 10237.9 7760.5 8147.7 8201.2 8217.1 8305.0 5779.2 5803.7 5864.9 5993.6 5996.4 9744.9 10067.1 10073.8 10095.2 10098.4 9596.4 9785.0 9907.4 9921.9 9955.4 6225.0 6923.4 7113.4 7529.3 7675.2 8232.3 8322.7 8430.4 8431.4 8436.4 7272.9 7337.6 7376.9 7498.1 7531.7 7309.9 7317.6 7347.5 7406.5 7423.7 6968.5 7005.4 7014.8 7043.6 7050.2 4683.1 4771.1 4851.1 4963.4 5028.7 4810.6 4910.4 4913.5 4923.8 5183.7 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4501.56 Median:5078.20 stddev:1400.68 (27.6%) stddev-sans-first:1368.82 06:38:25 INFO - PROCESS | 1669 | Values: 310.1 312.2 318.5 320.0 322.7 5334.5 5344.9 5345.5 5364.9 5374.3 4839.8 5253.7 5263.4 5281.5 5294.9 3647.8 3761.4 3885.9 3997.6 4058.4 3976.3 4044.0 4053.7 4089.4 4100.0 3955.0 3965.7 4020.4 4021.8 4023.4 3795.4 3860.3 3897.5 4045.0 4051.2 2936.8 3155.7 3530.6 3539.1 3543.3 4145.4 4695.0 4815.4 4829.5 5136.9 4090.0 4297.5 4580.9 4608.6 4639.0 5475.3 5540.1 5540.8 5544.4 5581.2 3385.7 3840.3 3855.0 3858.0 3858.3 4979.0 5486.9 5563.0 5805.3 5905.6 3841.0 3875.3 3889.5 3895.8 3896.3 3476.7 3498.6 3815.3 3930.5 3948.0 4406.9 4608.7 4617.6 4623.5 4634.5 5398.4 5599.2 5607.8 5638.6 5679.4 1322.0 1339.6 1342.0 1343.4 1347.0 677.4 805.3 805.8 813.7 813.9 4094.9 4544.3 4886.5 5216.4 5232.9 4901.5 5751.7 5783.2 5797.0 5799.4 4490.6 4623.9 4803.9 5119.2 5198.9 2592.0 2683.5 2984.0 2994.9 2997.6 4727.0 5474.0 5960.8 5974.4 6059.1 5430.2 5552.5 5853.5 5879.7 5963.9 4910.5 5996.4 5999.9 6015.9 6036.3 4928.5 5086.3 5472.6 5762.5 5819.4 5627.9 5705.9 5749.8 5759.6 5778.3 5376.7 5932.9 5978.5 5982.3 6056.8 3223.2 3579.8 3583.4 3817.2 3870.7 4809.8 5337.5 5580.0 5700.1 5720.5 5126.3 5502.3 5546.5 5566.7 5586.6 5014.9 5070.1 5556.9 5568.3 5599.7 4589.5 4653.7 5191.7 5434.2 5462.3 5387.6 5434.5 5453.5 5592.6 5606.0 5224.4 5409.7 5416.7 5457.8 5473.0 06:38:25 INFO - PROCESS | 1669 | 06:38:25 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:17474.91 Median:622.96 stddev:24504.33 (3933.5%) stddev-sans-first:24540.18 06:38:25 INFO - PROCESS | 1669 | Values: 565.3 625.2 722.6 729.5 730.2 12117.5 14535.5 14981.0 15258.9 15270.9 13814.1 13980.3 14091.2 14179.4 14180.1 720.7 744.9 907.3 925.2 930.1 878.2 886.6 910.8 923.9 926.9 928.2 958.3 965.0 966.8 968.8 761.9 928.7 933.2 964.2 966.0 709.1 717.7 720.0 721.4 722.2 14249.0 14399.9 14610.0 14655.0 14666.6 10994.7 11341.9 11561.5 11639.5 12320.1 9685.7 10189.6 10717.7 10917.6 11346.9 3347.2 3376.8 3449.5 3515.2 3581.5 80027.0 80078.6 80981.0 84136.6 84539.6 8976.7 9149.6 9216.7 9284.4 9456.9 20223.7 20579.2 20597.4 20613.6 20619.0 7201.0 7228.3 7266.4 7355.9 7438.6 3897.2 3926.8 3936.1 3981.3 4040.3 3431.2 3537.6 3542.5 3543.4 3548.5 522.0 620.7 630.9 637.0 645.5 489.2 509.4 525.4 543.5 595.8 8167.9 8224.5 8236.2 8304.7 8383.5 549.3 563.2 606.2 631.2 637.9 3675.0 3846.4 3869.1 3869.1 3993.1 86043.6 88227.0 88389.3 88577.6 89497.0 60282.0 74078.3 74343.3 75359.6 76549.0 69226.7 69241.7 69308.3 69551.7 69624.0 7603.9 7715.8 7738.4 7797.3 7868.9 34195.7 44495.9 44798.3 44955.8 45369.5 58214.3 58752.4 60395.4 63062.1 63090.7 9856.0 10136.2 10270.7 10368.9 10394.0 11459.8 11468.1 11927.8 12049.8 12291.8 8740.6 11488.5 11510.5 11527.8 11896.9 11321.9 11385.6 11396.0 11700.0 11740.3 8851.4 11221.7 11398.2 11506.6 11536.3 7286.7 9312.4 9387.4 9439.7 9773.0 9246.2 9876.1 9882.4 9900.0 9916.9 06:38:25 INFO - PROCESS | 1669 | -------- Summary: end -------- 06:38:25 INFO - PROCESS | 1669 | 06:38:26 INFO - TEST-INFO | 1669: exit 0 06:38:26 INFO - TEST-OK | dromaeo_css | took 1114711ms 06:38:26 INFO - TEST-START | kraken 06:38:26 INFO - operating with platform_type : mac_ 06:38:26 INFO - Initialising browser for kraken test... 06:38:26 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpTX8PAk/profile http://localhost:49270/getInfo.html) 06:38:28 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 06:38:28 INFO - PROCESS | 1675 | colorDepth:24 06:38:28 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 06:38:28 INFO - PROCESS | 1675 | __metrics 06:38:28 INFO - PROCESS | 1675 | 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" 06:38:28 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:38:29 INFO - TEST-INFO | 1675: exit 0 06:38:29 INFO - Browser initialized. 06:38:29 INFO - Running cycle 1/1 for kraken test... 06:38:29 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/T/tmpTX8PAk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:38:41 INFO - PROCESS | 1677 | RSS: Main: 239398912 06:38:41 INFO - PROCESS | 1677 | 06:38:44 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 06:38:44 INFO - PROCESS | 1677 | RSS: Main: 286191616 06:38:44 INFO - PROCESS | 1677 | 06:38:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 06:38:48 INFO - PROCESS | 1677 | RSS: Main: 295268352 06:38:48 INFO - PROCESS | 1677 | 06:38:51 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 06:38:52 INFO - PROCESS | 1677 | RSS: Main: 385437696 06:38:52 INFO - PROCESS | 1677 | 06:38:54 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 06:38:55 INFO - PROCESS | 1677 | RSS: Main: 284753920 06:38:55 INFO - PROCESS | 1677 | 06:38:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:38:57 INFO - PROCESS | 1677 | RSS: Main: 290054144 06:38:57 INFO - PROCESS | 1677 | 06:39:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 06:39:01 INFO - PROCESS | 1677 | RSS: Main: 327827456 06:39:01 INFO - PROCESS | 1677 | 06:39:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:04 INFO - PROCESS | 1677 | RSS: Main: 325808128 06:39:04 INFO - PROCESS | 1677 | 06:39:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 06:39:08 INFO - PROCESS | 1677 | RSS: Main: 327385088 06:39:08 INFO - PROCESS | 1677 | 06:39:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:10 INFO - PROCESS | 1677 | RSS: Main: 313057280 06:39:10 INFO - PROCESS | 1677 | 06:39:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:39:12 INFO - PROCESS | 1677 | RSS: Main: 320708608 06:39:12 INFO - PROCESS | 1677 | 06:39:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:39:15 INFO - PROCESS | 1677 | RSS: Main: 321368064 06:39:15 INFO - PROCESS | 1677 | 06:39:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:39:19 INFO - PROCESS | 1677 | RSS: Main: 326160384 06:39:19 INFO - PROCESS | 1677 | 06:39:21 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:39:22 INFO - PROCESS | 1677 | RSS: Main: 323702784 06:39:22 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 06:39:24 INFO - PROCESS | 1677 | RSS: Main: 370253824 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | __start_tp_report 06:39:24 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 06:39:24 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 06:39:24 INFO - PROCESS | 1677 | |i|pagename|runs| 06:39:24 INFO - PROCESS | 1677 | |0;ai-astar;110;106;112;110;111;110;111;110;110;111 06:39:24 INFO - PROCESS | 1677 | |1;audio-beat-detection;155;126;112;196;140;138;156;138;149;118 06:39:24 INFO - PROCESS | 1677 | |2;audio-dft;253;252;182;183;183;183;180;180;182;178 06:39:24 INFO - PROCESS | 1677 | |3;audio-fft;74;48;52;142;80;81;90;47;108;47 06:39:24 INFO - PROCESS | 1677 | |4;audio-oscillator;87;69;99;70;71;69;95;70;68;98 06:39:24 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;87;86;87;86;87;85;89;85;87;84 06:39:24 INFO - PROCESS | 1677 | |6;imaging-darkroom;92;93;92;93;93;92;94;93;92;93 06:39:24 INFO - PROCESS | 1677 | |7;imaging-desaturate;84;86;85;85;86;86;86;86;86;85 06:39:24 INFO - PROCESS | 1677 | |8;json-parse-financial;54;55;56;55;54;55;55;74;57;55 06:39:24 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;75;68;77;70;72;68;68;69;72 06:39:24 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;86;85;87;85;84;85;81;86;87;88 06:39:24 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;141;139;138;138;138;144;145;149;190;136 06:39:24 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;163;165;163;166;164;166;163;162;160;167 06:39:24 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;66;66;66;65;65;67;70;78;82;80 06:39:24 INFO - PROCESS | 1677 | __end_tp_report 06:39:24 INFO - PROCESS | 1677 | __start_cc_report 06:39:24 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1988 06:39:24 INFO - PROCESS | 1677 | __end_cc_report 06:39:24 INFO - PROCESS | 1677 | __startTimestamp1464183564528__endTimestamp 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | ------- Summary: start ------- 06:39:24 INFO - PROCESS | 1677 | Number of tests: 14 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:110.10 Median:110.50 stddev:1.60 (1.4%) stddev-sans-first:1.69 06:39:24 INFO - PROCESS | 1677 | Values: 110.0 106.0 112.0 110.0 111.0 110.0 111.0 110.0 110.0 111.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:144.50 stddev:23.79 (16.5%) stddev-sans-first:24.81 06:39:24 INFO - PROCESS | 1677 | Values: 155.0 126.0 112.0 196.0 140.0 138.0 156.0 138.0 149.0 118.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:195.60 Median:183.00 stddev:30.03 (16.4%) stddev-sans-first:23.60 06:39:24 INFO - PROCESS | 1677 | Values: 253.0 252.0 182.0 183.0 183.0 183.0 180.0 180.0 182.0 178.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:76.90 Median:63.00 stddev:31.01 (49.2%) stddev-sans-first:32.87 06:39:24 INFO - PROCESS | 1677 | Values: 74.0 48.0 52.0 142.0 80.0 81.0 90.0 47.0 108.0 47.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:79.00 stddev:13.43 (17.0%) stddev-sans-first:13.98 06:39:24 INFO - PROCESS | 1677 | Values: 87.0 69.0 99.0 70.0 71.0 69.0 95.0 70.0 68.0 98.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.42 (1.6%) stddev-sans-first:1.48 06:39:24 INFO - PROCESS | 1677 | Values: 87.0 86.0 87.0 86.0 87.0 85.0 89.0 85.0 87.0 84.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 06:39:24 INFO - PROCESS | 1677 | Values: 92.0 93.0 92.0 93.0 93.0 92.0 94.0 93.0 92.0 93.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:0.71 (0.8%) stddev-sans-first:0.50 06:39:24 INFO - PROCESS | 1677 | Values: 84.0 86.0 85.0 85.0 86.0 86.0 86.0 86.0 86.0 85.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:55.00 stddev:6.04 (11.0%) stddev-sans-first:6.30 06:39:24 INFO - PROCESS | 1677 | Values: 54.0 55.0 56.0 55.0 54.0 55.0 55.0 74.0 57.0 55.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.00 stddev:3.80 (5.4%) stddev-sans-first:3.28 06:39:24 INFO - PROCESS | 1677 | Values: 64.0 75.0 68.0 77.0 70.0 72.0 68.0 68.0 69.0 72.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:1.96 (2.3%) stddev-sans-first:2.06 06:39:24 INFO - PROCESS | 1677 | Values: 86.0 85.0 87.0 85.0 84.0 85.0 81.0 86.0 87.0 88.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:142.50 stddev:16.04 (11.3%) stddev-sans-first:16.92 06:39:24 INFO - PROCESS | 1677 | Values: 141.0 139.0 138.0 138.0 138.0 144.0 145.0 149.0 190.0 136.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.90 Median:164.50 stddev:2.13 (1.3%) stddev-sans-first:2.24 06:39:24 INFO - PROCESS | 1677 | Values: 163.0 165.0 163.0 166.0 164.0 166.0 163.0 162.0 160.0 167.0 06:39:24 INFO - PROCESS | 1677 | 06:39:24 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:68.50 stddev:6.77 (9.9%) stddev-sans-first:6.98 06:39:24 INFO - PROCESS | 1677 | Values: 66.0 66.0 66.0 65.0 65.0 67.0 70.0 78.0 82.0 80.0 06:39:24 INFO - PROCESS | 1677 | -------- Summary: end -------- 06:39:24 INFO - PROCESS | 1677 | 06:39:25 INFO - TEST-INFO | 1677: exit 0 06:39:25 INFO - TEST-OK | kraken | took 59074ms 06:39:25 INFO - SUITE-END | took 1173s 06:39:25 INFO - Completed test suite (00:19:34) 06:39:25 INFO - javascript benchmark 06:39:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3400.319936012798, 3408.4205685033544, 3426.914327141822, 3442.7073698735608, 3455.222574920643, 5538.7784488620455, 5556.555475561945, 5625.099984002561, 5642.238297829792, 5680.545556355487, 5544.6395984261, 5563.332400111992, 5573.637713548616, 5593.105103183491, 5727.11229759388, 5034.622403319748, 5096.566791822681, 5136.5890728741615, 5139.331886854704, 5290.814821481249, 5234.790608375661, 5270.868228294305, 5295.338082739658, 5302.946970530304, 5337.012652659251, 5042.873928151789, 5113.923291150636, 5127.102757017507, 5141.922871156936, 5161.761177317454, 4474.105178964221, 4914.385701787277, 5123.590112790967, 5130.025295193938, 5146.794128234904, 5095.184770436712, 5165.7158856262895, 5169.0, 5180.6373553851245, 5203.959208158383, 4406.361077643724, 5524.14375771752, 5526.281583394173, 5527.695976721278, 5602.663840169603, 6184.536159787989, 6185.783497577563, 6186.288576813634, 6209.254889413254, 6218.31598524162, 5226.869328266824, 6211.47202487789, 6257.061440783873, 6278.748850046039, 6291.552942823177, 5611.859703507404, 5674.943250567524, 5720.1991721159075, 5724.627899186539, 5781.971090144564, 6282.780102696383, 6294.464970477561, 6296.244450665858, 6300.716467758962, 6309.747610095545, 6100.89988024788, 6106.809172211376, 6111.294303386368, 6114.367021223701, 6132.601380910227, 5985.551083668743, 6130.826205142237, 6143.385661433821, 6180.412860778219, 6208.596441231305, 6122.0, 6167.259928808572, 6169.475594574512, 6182.783602573978, 6188.0, 6201.875962480725, 6203.875922481525, 6219.968900155471, 6230.937690623035, 6248.687565621701, 4689.6717229793585, 4751.287306903992, 4751.976240118777, 4859.198232291632, 4947.975260123676, 4330.133973205308, 4341.0, 4387.341898715218, 4412.536683648235, 4456.665750068693, 5560.7219639017885, 5575.330960284792, 5579.163125531121, 5585.636933599303, 5587.022271102541, 6030.638161710312, 6111.755529778759, 6131.560175786049, 6230.750769969241, 6236.03341482071, 5595.804146854841, 5609.354924183771, 5615.326160860723, 5624.437556244343, 5675.858103547362, 5268.789248430096, 5326.586523346848, 5336.946630533645, 5343.759530821122, 5353.277307563507, 6200.0, 6229.719662615284, 6248.156498872594, 6266.780662676784, 6271.937280627227, 6223.0, 6232.781852635318, 6242.875142497215, 6249.375062493714, 6250.124982502544, 6270.12218289449, 6270.278917924497, 6281.371862813682, 6286.65423401699, 6289.874202515833, 5902.527797776082, 6046.153538504701, 6068.939310606837, 6105.694715264308, 6136.447719705248, 6010.602850635168, 6166.445019948226, 6167.528252882646, 6189.6286222826775, 6204.689765511796, 5978.342382338026, 5985.610935289193, 5989.401059893976, 5991.0, 6001.5798894077, 5775.306963164448, 5884.264466941632, 5932.49573786233, 5948.494377977747, 6011.128386384037, 5808.157817116579, 5819.476247137609, 5822.388649191774, 5832.854178645568, 5881.911771323519, 5797.159411885169, 6025.698715064317, 6045.848853778691, 6046.365131661112, 6072.72672729737, 4996.975619997965, 5789.305283366023, 5861.6189947653265, 5865.384134665798, 5897.67562784051], "value": 5618.496542526549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.8636868045628, 515.1294312611686, 517.8757098296408, 550.1774846604327, 558.7876606889382, 30800.845995770018, 31414.842925785368, 41923.58076419239, 42987.0, 43158.0, 44505.219791208394, 44912.65262042136, 44988.550114498845, 45106.0, 46033.30950035752, 9545.331826772128, 9763.511824408786, 9765.12113909748, 9777.0, 9786.3638863474, 9544.0, 9616.374935629161, 9677.564509597085, 9702.369345992487, 9716.61133554657, 9655.710328690115, 9683.90316096837, 9711.223102151846, 9725.367851089692, 9745.658901938434, 9646.951765241163, 9671.516424178762, 9682.031796820262, 9691.66079187232, 9700.369475984075, 9577.856332155023, 9690.95154524223, 9701.66044188457, 9702.0, 9704.417734935927, 40210.79894600508, 40329.0, 40635.78092657225, 40734.777956661346, 40835.979100522425, 2627.5533159362953, 3184.7452203823636, 3383.1880348716377, 3468.0982944434377, 3472.7048200902955, 28521.0, 28934.131976040753, 29154.833806647708, 29247.85376073106, 29396.70603293961, 2723.237493501823, 2729.686086100104, 2738.5344491436504, 2757.806953513255, 2825.22306365749, 517844.0, 518719.0, 519015.40492297674, 519671.40164299315, 521329.0, 59418.702906486054, 59570.70214648986, 59953.400465995655, 60259.096113558335, 60354.0, 54320.0, 55296.447035529935, 61599.6920015406, 63038.0, 63391.0, 15817.920910395604, 16608.169591520375, 16610.916945415196, 16622.418215362646, 16626.0, 12862.0, 12957.898578620923, 12984.091113622144, 13004.414801333962, 13004.609861704164, 13694.93152534231, 15440.536783896501, 15846.207689615474, 15854.762178567331, 15879.603009924844, 5519.9448005520235, 6448.806535803933, 6595.307492713292, 6603.8349041273395, 6616.305287944697, 843.7806170395742, 897.3539051882634, 900.7883147460342, 975.8243516167158, 982.577491678585, 24403.26790196297, 24854.0, 29482.0, 29720.55419168714, 30025.549616755765, 1076.3488089705772, 1102.8014957307762, 1145.788029214598, 1146.7935771561033, 1170.607846371475, 13007.869921300666, 15032.473863414729, 15096.924515377354, 15112.697746045018, 15411.15238661862, 562692.0, 611921.0, 618612.0, 625434.0, 637170.0, 59977.0, 60067.69966150229, 60205.0, 60290.69854650786, 60335.69832150811, 1392.5822253323963, 1410.8095407120113, 1448.7319845828345, 1449.2029383839058, 1451.3759083594157, 25912.352191195372, 28753.0, 28760.0, 29003.419931600827, 29806.403871922874, 50288.7427814293, 51358.48641513687, 51577.48422515727, 51731.482685174175, 51855.740721296155, 564926.0, 567902.0, 568523.0, 568684.0, 569886.0, 33027.0, 39042.0, 39536.60463395444, 39679.0, 41011.58988410197, 36906.81546592357, 41681.0, 45714.77142614266, 48644.756776215894, 48792.756036219595, 37795.81102094472, 37961.4305785419, 38052.42921356126, 38161.80919095387, 38578.6142138575, 36917.0, 37068.814655926544, 37971.43042854415, 38113.2377352457, 38130.237395252494, 36260.0, 36917.0, 37001.259974800894, 37405.438918415704, 38148.61851381451, 32915.83542082274, 33349.33301334008, 33353.83323083369, 33490.82782102615, 33577.49633755544, 24098.0, 24220.0, 24343.87828060919, 25105.99576016939, 25133.120340787984], "value": 19745.221638245122, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.19468933826283, 380.35719633818854, 385.3756913799645, 396.4034128636402, 402.840877853248, 19377.806221937775, 20532.486687832792, 20577.38267851967, 20588.0, 21247.468813279676, 20438.0, 20844.270450534234, 20901.0, 21390.679139812877, 21529.67705484419, 5250.9474905250845, 5906.734196961147, 6026.5178785697035, 6070.5447091468095, 6078.39216078393, 5909.113632955048, 6001.699915004232, 6024.337322894478, 6027.758889644409, 6033.818985430444, 5630.662160270397, 5988.431099045583, 5989.850253743648, 5996.340402555716, 6011.218541589587, 5915.556333275, 5991.520678345743, 5992.131140984533, 6070.362611925772, 6643.202815662103, 5729.398413166641, 5830.096335068071, 5845.824625261249, 5855.4144585541535, 5857.062869940789, 13376.130551514121, 20445.2844150454, 21501.03245353963, 25586.744132558808, 26106.477870442486, 1852.2498388152685, 2004.2984955265686, 2021.444102871722, 2060.072967164782, 2227.487677834091, 9488.0, 9810.21518278536, 9908.90091099087, 9972.750681233027, 10114.54484548197, 1704.318272690921, 1711.5036639374566, 1748.7551742756025, 1775.7691500104904, 1892.4984879007075, 110461.23846903811, 112220.43889780498, 113526.0, 120953.39523302327, 122841.0, 11361.431928403546, 11424.0, 11567.595134170264, 11723.472443740053, 11880.287182769061, 14837.183954882585, 14935.178565178801, 14964.251787410803, 14970.026948248329, 14980.250987450583, 8541.444806087584, 8620.52587107715, 8739.912600874037, 8852.645894164161, 8872.73381798547, 8629.827403451896, 8647.437916535406, 8660.480371177624, 8727.389082764272, 8759.605817738217, 3655.707543396522, 4560.657950653715, 4594.19601569724, 4644.814207431732, 4654.37165982588, 1855.925762969493, 1900.4868685454849, 1919.7984211657854, 1923.519120219945, 1968.2323893681476, 717.1107826295356, 908.2053203446984, 921.5208091792231, 963.3978763272954, 1022.4826596507988, 18487.613428992878, 19518.5120371989, 19692.0, 20525.739649406776, 20598.70566297375, 916.3356566489375, 922.1239822168966, 950.5152372290182, 962.7208109648263, 978.4814048554277, 8173.509589424653, 10844.620438284623, 10856.131509479223, 10879.456027198608, 10920.617778377718, 458654.0, 559164.0, 573257.0, 576459.0, 578469.0, 132707.0, 133987.0, 135156.3242183783, 136495.0, 138447.30776346053, 990.6730778843108, 991.9950400248097, 998.5257002923553, 1000.9699709008742, 1011.0748664971616, 13569.86430135686, 17447.0, 17965.73051404204, 18257.178426971084, 18299.81700182981, 37875.81062094672, 50633.49366506435, 51709.0, 53816.730916345165, 54109.45890541044, 71324.0, 73553.0, 74592.25407745854, 75180.0, 76992.23007770075, 6937.132858392701, 7423.92576074254, 7453.4782565222085, 7483.775486735407, 7591.848163036598, 12030.699232519257, 12519.123661343983, 12713.300768457453, 13015.544455943997, 13066.216027038408, 10819.837702434626, 10962.397068161328, 10995.395253260827, 11128.443577820915, 11374.829377559177, 10728.73178170552, 10769.0, 10817.45912704346, 11243.718907027389, 11430.771384572428, 10942.781144376908, 11094.223404361617, 11266.816984216539, 11270.703869055164, 11299.209055366375, 6854.862902742017, 6996.300369962963, 7312.707491700273, 7458.105027396748, 7749.070111586646, 9357.262311858985, 10793.838092428463, 11066.667999959693, 11089.722756931142, 11092.722681933015], "value": 9377.235405235808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.5915454940791, 1539.3303912797933, 1546.512848452737, 1575.787268718723, 1944.4944314478232, 7847.372210223182, 8746.650133994632, 8933.731988040354, 8967.596458159374, 8969.19277265046, 8671.523066231355, 8746.344024198179, 8910.064443233448, 8918.91081089189, 8961.148690874357, 2358.1864256831363, 2391.497785465054, 2392.449736560592, 2396.4488167721347, 2408.662787209792, 2013.3154727392684, 2347.8239132065164, 2372.4424760181346, 2392.138830021191, 2394.844335118212, 2351.0595761695286, 2361.3624321433203, 2365.7161140663143, 2384.3443053160345, 2385.654080158376, 2184.530325979922, 2212.126210146991, 2214.092222188911, 2218.0462401167492, 2237.8433509654333, 2320.3619004773655, 2358.4575547624145, 2361.315218586608, 2362.14962613459, 2365.940851478727, 8600.5699715014, 8954.597043133075, 8987.236084932789, 8987.730368088967, 9005.14451127142, 5213.478652134794, 5428.4028756836715, 5431.293931788881, 5432.511074003319, 5442.6462279951675, 3451.775634583744, 3528.1356067763563, 3593.5148754918273, 3610.3681855675413, 3613.7109031277437, 1021.0402221911379, 1030.5156576409076, 1035.1977217656301, 1036.8392899100577, 1039.1946241662697, 11684.123690723145, 11781.46983385741, 11897.762044759056, 11917.404129793476, 11932.582359617458, 4507.752073635916, 4543.591076803037, 4656.906861862812, 4661.86014419568, 4696.0, 7980.4812687175145, 9138.451692898447, 9217.95391023054, 9266.027067157986, 9284.0, 5249.632525723239, 5268.262443257947, 5278.392984806719, 5280.1815718563685, 5280.867978300496, 4408.53710360414, 4522.3668686383935, 4693.0, 4796.904061918742, 4939.086269040239, 3461.5846098468346, 3997.560268370477, 3998.100427403811, 4084.346504559256, 4122.319817230124, 1281.6859869332072, 1299.662087857164, 1300.473308310136, 1310.502009236503, 1313.7832257677376, 793.2583034862422, 881.2773525708985, 882.896379525593, 924.9825192288429, 941.8163458125599, 5523.088690366125, 5655.660660360391, 5823.5923485355615, 5972.313183983897, 5972.552058595623, 877.6664867350494, 909.077286554148, 922.6309476209569, 928.6981730937472, 932.6409332407072, 3861.5559210690567, 3867.361885288952, 3967.2263908538134, 4029.6574791142493, 4075.4294398784195, 9887.80224395508, 10061.0, 10075.244356673278, 10270.127039201752, 10294.17646588271, 10017.799644007078, 10080.495975201211, 10231.13035391985, 10233.590656373663, 10237.897621023843, 7760.495567788075, 8147.714829981041, 8201.22088401601, 8217.137200593972, 8305.044919834296, 5779.219805326227, 5803.709814509173, 5864.853378665567, 5993.640381577119, 5996.37038110992, 9744.85382719274, 10067.1442927352, 10073.798524029626, 10095.192384609361, 10098.444585547866, 9596.424214547149, 9784.951075244579, 9907.356021858555, 9921.851172232278, 9955.352902061342, 6225.0, 6923.376896079176, 7113.359797618239, 7529.284717951786, 7675.155732869492, 8232.300254478198, 8322.6670933162, 8430.36772242072, 8431.36764742659, 8436.409451338348, 7272.854542909006, 7337.59643219628, 7376.852462950604, 7498.100227972678, 7531.661075251517, 7309.853802924018, 7317.634118294171, 7347.485676002842, 7406.481546291708, 7423.665935033045, 6968.477364197807, 7005.404540614105, 7014.789556313319, 7043.647817608997, 7050.189228238613, 4683.110209060268, 4771.117343291502, 4851.102546028996, 4963.404391473046, 5028.698278103326, 4810.59109975642, 4910.435299940553, 4913.508649135058, 4923.803047878188, 5183.740812959412], "value": 4539.155724920062, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.13936326693425, 312.2038801057303, 318.5222166749876, 319.9552062711221, 322.7418065547561, 5334.466553344669, 5344.85085706573, 5345.514335833836, 5364.865878353038, 5374.274472946151, 4839.830605928793, 5253.684778913268, 5263.394709608397, 5281.524662780349, 5294.92057619136, 3647.777994371882, 3761.4169803680475, 3885.8834234972996, 3997.6402123808853, 4058.431819545266, 3976.324024915771, 4044.0, 4053.6757059435176, 4089.447924530195, 4100.0, 3954.960450395488, 3965.6827453803626, 4020.376841589544, 4021.8190181441905, 4023.390643742497, 3795.443868014101, 3860.343741563941, 3897.512810898638, 4045.0, 4051.1897620476025, 2936.7797415193945, 3155.727875929327, 3530.6469353064745, 3539.1152211947015, 3543.326767914088, 4145.419641250227, 4694.990577025956, 4815.41251767432, 4829.492903245144, 5136.922946155811, 4089.9591004089875, 4297.527272000078, 4580.88547786308, 4608.6082682971655, 4639.025804581042, 5475.315585551806, 5540.063766203918, 5540.750666220029, 5544.390117087117, 5581.2186293918885, 3385.712214461774, 3840.327942610032, 3854.9614503855164, 3858.0, 3858.2669292834553, 4978.975105124451, 5486.862828429281, 5562.972185139089, 5805.303363596396, 5905.616134951214, 3841.0205397623427, 3875.302445559826, 3889.5138107736534, 3895.766254024711, 3896.298666240047, 3476.66971637694, 3498.5626796650417, 3815.256025075084, 3930.4890364252465, 3948.0, 4406.889827754332, 4608.700434471749, 4617.607503362185, 4623.474253496405, 4634.467036290802, 5398.352197736218, 5599.160125981135, 5607.80372686954, 5638.605297629127, 5679.403662615448, 1322.0, 1339.55125033115, 1342.0471465259775, 1343.361903096041, 1346.9730605387838, 677.3565113142535, 805.3476683886071, 805.7663277649416, 813.6663967773185, 813.943023988327, 4094.856680016245, 4544.318352247189, 4886.462489126193, 5216.40011398694, 5232.901090770904, 4901.534354236342, 5751.712414379264, 5783.161441590946, 5797.0, 5799.43415277881, 4490.550944905483, 4623.86128416148, 4803.903921921542, 5119.206522988943, 5198.92201616976, 2591.9870400647874, 2683.490136873988, 2984.0, 2994.9101526954228, 2997.5503674449005, 4726.976365118221, 5473.972630136824, 5960.76156953717, 5974.3726908674835, 6059.0608455689435, 5430.15832545956, 5552.473069905727, 5853.473187413153, 5879.706014699248, 5963.940360596339, 4910.48439913811, 5996.400359963969, 5999.940000600025, 6015.909761353583, 6036.3360030396625, 4928.490431255361, 5086.262491938574, 5472.644278121909, 5762.481376676119, 5819.447152520504, 5627.874425114912, 5705.857353566194, 5749.770009199752, 5759.625624334405, 5778.335491418541, 5376.688024149496, 5932.911006334822, 5978.521718262616, 5982.252218472691, 6056.757729690763, 3223.2264256577782, 3579.82100894949, 3583.4053846039305, 3817.167759475523, 3870.70969677281, 4809.78355973989, 5337.546308563816, 5580.0, 5700.144978253295, 5720.513756330759, 5126.333576635163, 5502.284702988586, 5546.500814926676, 5566.666000040011, 5586.636868603528, 5014.9498505015945, 5070.138076527073, 5556.944180605672, 5568.276124103841, 5599.720013999202, 4589.541045895384, 4653.697509661861, 5191.714455704972, 5434.157705555645, 5462.306684927685, 5387.622866399314, 5434.538064264424, 5453.481919217668, 5592.552595792408, 5605.971970140124, 5224.425313215619, 5409.729513524387, 5416.674999500043, 5457.808976685797, 5472.960137573848], "value": 4051.3188046242367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.3357305166429, 625.2372106030643, 722.5772922840139, 729.4784229276066, 730.218666027351, 12117.515299388015, 14535.491257805965, 14981.0, 15258.923705381472, 15270.923645381772, 13814.102083364574, 13980.300984950763, 14091.154530728163, 14179.432822687106, 14180.149191048544, 720.7009091227135, 744.8770952792783, 907.2560500389667, 925.1534845616287, 930.0652843891879, 878.1789027259501, 886.6497733395305, 910.7723069232692, 923.8845144356956, 926.9351145419823, 928.206383542072, 958.290864760076, 964.9915837949325, 966.7824739433642, 968.8207681578896, 761.9352355049799, 928.7074571509986, 933.1881263300904, 964.2478866484154, 966.0291407135853, 709.0534136927157, 717.7487879242276, 719.9992011104569, 721.4408833154347, 722.1803253307481, 14249.002569820117, 14399.928000360036, 14610.0, 14655.0, 14666.56000319992, 10994.680638323453, 11341.943290283496, 11561.479733412016, 11639.47622356996, 12320.137590368679, 9685.70942871715, 10189.59241630334, 10717.678469645845, 10917.563297468092, 11346.943265283704, 3347.2468694543777, 3376.814275214887, 3449.4653328733834, 3515.226650136966, 3581.4985901973746, 80026.9973002695, 80078.59960700218, 80981.0, 84136.57931710302, 84539.57730211371, 8976.68581599654, 9149.588268527932, 9216.677416290398, 9284.442933423881, 9456.90543094574, 20223.696644550342, 20579.176832926514, 20597.382078537667, 20613.58772824535, 20619.0, 7201.0, 7228.31331023552, 7266.382357499671, 7355.926440735631, 7438.5536867788105, 3897.162110146332, 3926.784026878549, 3936.1340505088833, 3981.2833689936087, 4040.2929487340184, 3431.24512607226, 3537.5754909411035, 3542.4863394807608, 3543.36219480496, 3548.485469606944, 521.9765110570033, 620.6865532905854, 630.9022101574263, 636.961782293064, 645.4771634975684, 489.19527377463874, 509.36075225591645, 525.4167873660234, 543.4918351341429, 595.8154357766357, 8167.877481837777, 8224.506529608243, 8236.217559331853, 8304.667813287522, 8383.455075420079, 549.2804426201689, 563.2452513631753, 606.2482521673181, 631.2015300644688, 637.9330170332048, 3674.9816250918575, 3846.3845784674318, 3869.0520822398694, 3869.1294458746556, 3993.0815912339745, 86043.56978215194, 88227.0, 88389.25469844439, 88577.55711221402, 89497.0, 60282.0, 74078.25921740821, 74343.25656743364, 75359.62320188365, 76549.0, 69226.65386673034, 69241.65379173071, 69308.3069169312, 69551.65224173947, 69623.95564066543, 7603.923960760321, 7715.845683086419, 7738.41961852852, 7797.259260370256, 7868.8819667706175, 34195.658043419244, 44495.8822185361, 44798.328025079, 44955.77522112369, 45369.54630453653, 58214.253572392896, 58752.4124758764, 60395.39604604073, 63062.05406918808, 63090.68454657881, 9855.950720246354, 10136.239782016526, 10270.743231418975, 10368.948155259175, 10394.0, 11459.828102578635, 11468.139889508153, 11927.76144477123, 12049.759004820029, 12291.815622765844, 8740.562971851508, 11488.48301826403, 11510.48202830858, 11527.76944461123, 11896.940515297369, 11321.943390283326, 11385.601503947322, 11396.031337336088, 11700.0, 11740.295582265091, 8851.380403371702, 11221.719457013314, 11398.20212585111, 11506.597269095542, 11536.307821530734, 7286.6720997556295, 9312.394694344846, 9387.389819661701, 9439.669611563837, 9772.951135244279, 9246.214071803972, 9876.111149996535, 9882.357646752938, 9899.950500247453, 9916.900830991886], "value": 6689.875464690308, "unit": "score"}], "name": "dromaeo_css", "value": 7099.026576808866, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 112.0, 110.0, 111.0, 110.0, 111.0, 110.0, 110.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 112.0, 196.0, 140.0, 138.0, 156.0, 138.0, 149.0, 118.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [253.0, 252.0, 182.0, 183.0, 183.0, 183.0, 180.0, 180.0, 182.0, 178.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 48.0, 52.0, 142.0, 80.0, 81.0, 90.0, 47.0, 108.0, 47.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 99.0, 70.0, 71.0, 69.0, 95.0, 70.0, 68.0, 98.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 87.0, 85.0, 89.0, 85.0, 87.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 94.0, 93.0, 92.0, 93.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 85.0, 86.0, 86.0, 86.0, 86.0, 86.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 55.0, 54.0, 55.0, 55.0, 74.0, 57.0, 55.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 68.0, 77.0, 70.0, 72.0, 68.0, 68.0, 69.0, 72.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 85.0, 84.0, 85.0, 81.0, 86.0, 87.0, 88.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 138.0, 138.0, 138.0, 144.0, 145.0, 149.0, 190.0, 136.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 166.0, 164.0, 166.0, 163.0, 162.0, 160.0, 167.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 65.0, 67.0, 70.0, 78.0, 82.0, 80.0], "value": 70.5, "unit": "score"}], "name": "kraken", "value": 1462.4, "alertThreshold": 2.0}]} 06:39:25 INFO - Return code: 0 06:39:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:39:25 ERROR - # TBPL SUCCESS # 06:39:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:39:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:39:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:39:25 INFO - 2016-05-25 06:39:25.800 screenresolution[1681:10326] starting screenresolution argv=screenresolution get 06:39:25 INFO - 2016-05-25 06:39:25.802 screenresolution[1681:10326] Display 0: 1600x1200x32@60 06:39:25 INFO - 2016-05-25 06:39:25.808 screenresolution[1682:10329] starting screenresolution argv=screenresolution list 06:39:25 INFO - Available Modes on Display 0 06:39:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:39:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:39:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:39:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:39:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:39:26 INFO - Intel Iris: 06:39:26 INFO - Chipset Model: Intel Iris 06:39:26 INFO - Type: GPU 06:39:26 INFO - Bus: Built-In 06:39:26 INFO - VRAM (Dynamic, Max): 1536 MB 06:39:26 INFO - Vendor: Intel (0x8086) 06:39:26 INFO - Device ID: 0x0a2e 06:39:26 INFO - Revision ID: 0x0009 06:39:26 INFO - Displays: 06:39:26 INFO - Display: 06:39:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:39:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:39:26 INFO - Display Serial Number: 1600x1200 60 06:39:26 INFO - Main Display: Yes 06:39:26 INFO - Mirror: Off 06:39:26 INFO - Online: Yes 06:39:26 INFO - Rotation: Supported 06:39:26 INFO - Return code: 0 06:39:26 INFO - Running post-action listener: _resource_record_post_action 06:39:26 INFO - ##### 06:39:26 INFO - ##### Finished run-tests step (success) 06:39:26 INFO - ##### 06:39:26 INFO - Running post-run listener: _resource_record_post_run 06:39:26 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 31555072; Write bytes: 695130624; Read time: 196; Write time: 1902 06:39:26 INFO - TinderboxPrint: CPU usage
53.7% 06:39:26 INFO - TinderboxPrint: I/O read bytes / time
31,555,072 / 196 06:39:26 INFO - TinderboxPrint: I/O write bytes / time
695,130,624 / 1,902 06:39:26 INFO - TinderboxPrint: CPU idle
2,238.1 (46.3%) 06:39:26 INFO - TinderboxPrint: CPU system
967.4 (20.0%) 06:39:26 INFO - TinderboxPrint: CPU user
1,632.1 (33.7%) 06:39:26 INFO - TinderboxPrint: Swap in / out
423,911,424 / 0 06:39:26 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377922048; Write bytes: 370472448; Read time: 28400; Write time: 754 06:39:26 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30621184; Write bytes: 296576000; Read time: 185; Write time: 1081 06:39:26 INFO - Running post-run listener: _upload_blobber_files 06:39:26 INFO - Blob upload gear active. 06:39:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:39:26 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 06:39:26 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'] 06:39:26 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 06:39:26 INFO - (blobuploader) - INFO - Open directory for files ... 06:39:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:39:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:28 INFO - (blobuploader) - INFO - Done attempting. 06:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:29 INFO - (blobuploader) - INFO - Done attempting. 06:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:39:31 INFO - (blobuploader) - INFO - Done attempting. 06:39:31 INFO - (blobuploader) - INFO - Iteration through files over. 06:39:31 INFO - Return code: 0 06:39:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:39:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:39:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/227e46b4a98225d5f2524a60a486f8d18e84038117b932c226f1d959c24503a6437ff2ce455298470c453743505afd67e5c8810261cc398c581e46944036395a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f4bbcc5cbb21adfa136a741901460b1959fad313f2401048e3a8258370140a018c7049f052561bb30675bc9ffe7c9070e75feb0e4a7a70476faf1d101f37db9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42909689b11863d68e64dbae530fe2334248b0c06ec136bdcd3a469738bf615a5da467130da1a203b6e689babe848738cc826c7438b82617d11c3a07782d022a"} 06:39:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:39:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:39:31 INFO - Contents: 06:39:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/227e46b4a98225d5f2524a60a486f8d18e84038117b932c226f1d959c24503a6437ff2ce455298470c453743505afd67e5c8810261cc398c581e46944036395a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f4bbcc5cbb21adfa136a741901460b1959fad313f2401048e3a8258370140a018c7049f052561bb30675bc9ffe7c9070e75feb0e4a7a70476faf1d101f37db9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42909689b11863d68e64dbae530fe2334248b0c06ec136bdcd3a469738bf615a5da467130da1a203b6e689babe848738cc826c7438b82617d11c3a07782d022a"} 06:39:31 INFO - Running post-run listener: copy_logs_to_upload_dir 06:39:31 INFO - Copying logs to upload dir... 06:39:31 INFO - mkdir: /builds/slave/test/build/upload/logs 06:39:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.645046 ========= master_lag: 215.68 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 50 secs) (at 2016-05-25 06:43:07.137529) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-25 06:43:07.141173) ========= 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.JD1nBaPgcW/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.f5rLn9N6No/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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/227e46b4a98225d5f2524a60a486f8d18e84038117b932c226f1d959c24503a6437ff2ce455298470c453743505afd67e5c8810261cc398c581e46944036395a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f4bbcc5cbb21adfa136a741901460b1959fad313f2401048e3a8258370140a018c7049f052561bb30675bc9ffe7c9070e75feb0e4a7a70476faf1d101f37db9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42909689b11863d68e64dbae530fe2334248b0c06ec136bdcd3a469738bf615a5da467130da1a203b6e689babe848738cc826c7438b82617d11c3a07782d022a"} build_url:https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/PrnqgpbJSEqoomxT9chB0g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/227e46b4a98225d5f2524a60a486f8d18e84038117b932c226f1d959c24503a6437ff2ce455298470c453743505afd67e5c8810261cc398c581e46944036395a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f4bbcc5cbb21adfa136a741901460b1959fad313f2401048e3a8258370140a018c7049f052561bb30675bc9ffe7c9070e75feb0e4a7a70476faf1d101f37db9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/42909689b11863d68e64dbae530fe2334248b0c06ec136bdcd3a469738bf615a5da467130da1a203b6e689babe848738cc826c7438b82617d11c3a07782d022a"}' ========= master_lag: 1.00 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-25 06:43:08.149262) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-05-25 06:43:08.149578) ========= 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.JD1nBaPgcW/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.f5rLn9N6No/Listeners TMPDIR=/var/folders/pp/6_6758z1201by_tg1kk5wjkh00000w/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.005003 ========= master_lag: 4.93 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-05-25 06:43:13.085302) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-05-25 06:43:13.085610) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-05-25 06:43:19.091528) ========= ========= Total master_lag: 222.28 =========