builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0135 starttime: 1464793781.09 results: success (0) buildid: 20160601060544 builduid: 96bf6103b1444caf9290d603e8ac23f7 revision: 78e2125dfb6b02cdbab73a30c54bb4dc19aa310a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.090632) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.091085) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.091358) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.112362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.112694) ========= 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.EzAZoKS8YY/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.OdjKtstbwW/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-06-01 08:09:41-- 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% 74.7M=0s 2016-06-01 08:09:41 (74.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113899 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.246007) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.246320) ========= 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.EzAZoKS8YY/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.OdjKtstbwW/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.020307 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:41.295496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 08:09:41.295874) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 78e2125dfb6b02cdbab73a30c54bb4dc19aa310a --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 78e2125dfb6b02cdbab73a30c54bb4dc19aa310a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EzAZoKS8YY/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.OdjKtstbwW/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-06-01 08:09:41,373 truncating revision to first 12 chars 2016-06-01 08:09:41,373 Setting DEBUG logging. 2016-06-01 08:09:41,373 attempt 1/10 2016-06-01 08:09:41,373 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/78e2125dfb6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 08:09:42,422 unpacking tar archive at: mozilla-central-78e2125dfb6b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 08:09:42.630538) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:42.631091) ========= script_repo_revision: 78e2125dfb6b02cdbab73a30c54bb4dc19aa310a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:42.631742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:42.632222) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 08:09:42.649928) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-01 08:09:42.650388) ========= /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.EzAZoKS8YY/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.OdjKtstbwW/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:09:42 INFO - MultiFileLogger online at 20160601 08:09:42 in /builds/slave/test 08:09:42 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 08:09:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:42 INFO - {'append_to_log': False, 08:09:42 INFO - 'base_work_dir': '/builds/slave/test', 08:09:42 INFO - 'blob_upload_branch': 'Firefox', 08:09:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:42 INFO - 'branch': 'Firefox', 08:09:42 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:42 INFO - 'config_files': ('talos/mac_config.py',), 08:09:42 INFO - 'default_actions': ('clobber', 08:09:42 INFO - 'read-buildbot-config', 08:09:42 INFO - 'download-and-extract', 08:09:42 INFO - 'populate-webroot', 08:09:42 INFO - 'create-virtualenv', 08:09:42 INFO - 'install', 08:09:42 INFO - 'run-tests'), 08:09:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:42 INFO - 'download_minidump_stackwalk': True, 08:09:42 INFO - 'download_symbols': 'ondemand', 08:09:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:42 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:42 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:42 INFO - 'installer_path': 'installer.exe', 08:09:42 INFO - 'log_level': 'info', 08:09:42 INFO - 'log_name': 'talos', 08:09:42 INFO - 'log_to_console': True, 08:09:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:09:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:09:42 INFO - 'opt_config_files': (), 08:09:42 INFO - 'pip_index': False, 08:09:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:42 INFO - 'cmd': ('bash', 08:09:42 INFO - '-c', 08:09:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:42 INFO - 'enabled': True, 08:09:42 INFO - 'halt_on_failure': False, 08:09:42 INFO - 'name': 'check_screen_resolution'},), 08:09:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:42 INFO - 'cmd': ('bash', 08:09:42 INFO - '-c', 08:09:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:42 INFO - 'enabled': True, 08:09:42 INFO - 'halt_on_failure': False, 08:09:42 INFO - 'name': 'check_screen_resolution'},), 08:09:42 INFO - 'run_cmd_checks_enabled': True, 08:09:42 INFO - 'sps_profile': False, 08:09:42 INFO - 'sps_profile_interval': 0, 08:09:42 INFO - 'suite': 'dromaeojs', 08:09:42 INFO - 'system_bits': '32', 08:09:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:42 INFO - 'title': 't-yosemite-r7-0135', 08:09:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:42 INFO - 'use_talos_json': True, 08:09:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:42 INFO - 'work_dir': 'build'} 08:09:42 INFO - ##### 08:09:42 INFO - ##### Running clobber step. 08:09:42 INFO - ##### 08:09:42 INFO - Running pre-action listener: _resource_record_pre_action 08:09:42 INFO - Running main action method: clobber 08:09:42 INFO - rmtree: /builds/slave/test/build 08:09:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:44 INFO - Running post-action listener: _resource_record_post_action 08:09:44 INFO - ##### 08:09:44 INFO - ##### Finished clobber step (success) 08:09:44 INFO - ##### 08:09:44 INFO - ##### 08:09:44 INFO - ##### Running read-buildbot-config step. 08:09:44 INFO - ##### 08:09:44 INFO - Running pre-action listener: _resource_record_pre_action 08:09:44 INFO - Running main action method: read_buildbot_config 08:09:44 INFO - Using buildbot properties: 08:09:44 INFO - { 08:09:44 INFO - "project": "", 08:09:44 INFO - "product": "firefox", 08:09:44 INFO - "script_repo_revision": "production", 08:09:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:09:44 INFO - "repository": "", 08:09:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:09:44 INFO - "buildid": "20160601060544", 08:09:44 INFO - "pgo_build": "False", 08:09:44 INFO - "basedir": "/builds/slave/test", 08:09:44 INFO - "buildnumber": 12, 08:09:44 INFO - "slavename": "t-yosemite-r7-0135", 08:09:44 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:09:44 INFO - "platform": "yosemite_r7", 08:09:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:09:44 INFO - "branch": "mozilla-central", 08:09:44 INFO - "repo_path": "mozilla-central", 08:09:44 INFO - "revision": "78e2125dfb6b02cdbab73a30c54bb4dc19aa310a", 08:09:44 INFO - "stage_platform": "macosx64", 08:09:44 INFO - "builduid": "96bf6103b1444caf9290d603e8ac23f7", 08:09:44 INFO - "slavebuilddir": "test" 08:09:44 INFO - } 08:09:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:09:44 INFO - Running post-action listener: _resource_record_post_action 08:09:44 INFO - ##### 08:09:44 INFO - ##### Finished read-buildbot-config step (success) 08:09:44 INFO - ##### 08:09:44 INFO - ##### 08:09:44 INFO - ##### Running download-and-extract step. 08:09:44 INFO - ##### 08:09:44 INFO - Running pre-action listener: _resource_record_pre_action 08:09:44 INFO - Running main action method: download_and_extract 08:09:44 INFO - mkdir: /builds/slave/test/build/tests 08:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:44 INFO - https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:09:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:45 INFO - Downloaded 1199 bytes. 08:09:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:45 INFO - Using the following test package requirements: 08:09:45 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:09:45 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:09:45 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:09:45 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'jsshell-mac.zip'], 08:09:45 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:09:45 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:09:45 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:09:45 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:09:45 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:09:45 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:45 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:09:45 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:09: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')]} 08:09:45 INFO - https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:46 INFO - Downloaded 18687180 bytes. 08:09:46 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'] 08:09:46 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 08:09:49 INFO - Return code: 0 08:09:49 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 08:09: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')]} 08:09:49 INFO - https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:50 INFO - Downloaded 18687180 bytes. 08:09:50 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'] 08:09:50 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 08:09:54 INFO - Return code: 0 08:09:54 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')]} 08:09:54 INFO - https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:09:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:09:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:09:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:55 INFO - Downloaded 10834055 bytes. 08:09:55 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'] 08:09:55 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 08:09:55 INFO - Return code: 0 08:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:55 INFO - https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:09:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aevCuLtsRwyQtEw0kbGVKg/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 08:09:59 INFO - Downloaded 111012199 bytes. 08:09:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:59 INFO - mkdir: /builds/slave/test/properties 08:09:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:59 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:59 INFO - Contents: 08:09:59 INFO - build_url:https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:10:01 INFO - Running post-action listener: _resource_record_post_action 08:10:01 INFO - Running post-action listener: set_extra_try_arguments 08:10:01 INFO - ##### 08:10:01 INFO - ##### Finished download-and-extract step (success) 08:10:01 INFO - ##### 08:10:01 INFO - ##### 08:10:01 INFO - ##### Running populate-webroot step. 08:10:01 INFO - ##### 08:10:01 INFO - Running pre-action listener: _resource_record_pre_action 08:10:01 INFO - Running main action method: populate_webroot 08:10:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:01 INFO - '--tppagecycles', 08:10:01 INFO - '7'], 08:10:01 INFO - 'tests': ['tsvgm']}}, 08:10:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['damp', 'tps']}, 08:10:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'tests': ['damp', 'tps']}, 08:10:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['dromaeo_dom']}, 08:10:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['a11yr', 08:10:01 INFO - 'ts_paint', 08:10:01 INFO - 'tpaint', 08:10:01 INFO - 'sessionrestore', 08:10:01 INFO - 'sessionrestore_no_auto_restore', 08:10:01 INFO - 'tabpaint']}, 08:10:01 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:01 INFO - 'ts_paint', 08:10:01 INFO - 'tpaint', 08:10:01 INFO - 'sessionrestore', 08:10:01 INFO - 'sessionrestore_no_auto_restore', 08:10:01 INFO - 'tabpaint']}, 08:10:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:01 INFO - 'ts_paint', 08:10:01 INFO - 'tpaint', 08:10:01 INFO - 'sessionrestore', 08:10:01 INFO - 'sessionrestore_no_auto_restore', 08:10:01 INFO - 'tabpaint']}, 08:10:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:01 INFO - 'ts_paint', 08:10:01 INFO - 'tpaint', 08:10:01 INFO - 'sessionrestore', 08:10:01 INFO - 'sessionrestore_no_auto_restore', 08:10:01 INFO - 'tabpaint']}, 08:10:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['a11yr', 08:10:01 INFO - 'ts_paint', 08:10:01 INFO - 'tpaint', 08:10:01 INFO - 'sessionrestore', 08:10:01 INFO - 'sessionrestore_no_auto_restore', 08:10:01 INFO - 'tabpaint']}, 08:10:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['a11yr', 08:10:01 INFO - 'ts_paint', 08:10:01 INFO - 'tpaint', 08:10:01 INFO - 'sessionrestore', 08:10:01 INFO - 'sessionrestore_no_auto_restore', 08:10:01 INFO - 'tabpaint']}, 08:10:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['tsvgx', 08:10:01 INFO - 'tsvgr_opacity', 08:10:01 INFO - 'tart', 08:10:01 INFO - 'tscrollx', 08:10:01 INFO - 'cart']}, 08:10:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:01 INFO - 'tsvgr_opacity', 08:10:01 INFO - 'tart', 08:10:01 INFO - 'tscrollx', 08:10:01 INFO - 'cart']}, 08:10:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'talos_options': ['--disable-e10s'], 08:10:01 INFO - 'tests': ['tp5o']}, 08:10:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'tests': ['tp5o']}, 08:10:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'talos_options': ['--disable-e10s', 08:10:01 INFO - '--xperf_path', 08:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:01 INFO - 'tests': ['tp5n']}, 08:10:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:01 INFO - 'talos_options': ['--xperf_path', 08:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:01 INFO - 'tests': ['tp5n']}}, 08:10:01 INFO - 'talos.zip': {'path': '', 08:10:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:01 INFO - Running post-action listener: _resource_record_post_action 08:10:01 INFO - ##### 08:10:01 INFO - ##### Finished populate-webroot step (success) 08:10:01 INFO - ##### 08:10:01 INFO - ##### 08:10:01 INFO - ##### Running create-virtualenv step. 08:10:01 INFO - ##### 08:10:01 INFO - Running pre-action listener: _resource_record_pre_action 08:10:01 INFO - Running main action method: create_virtualenv 08:10:01 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:01 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 08:10:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:01 INFO - Using real prefix '/tools/python27' 08:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:04 INFO - Installing pip.................done. 08:10:04 INFO - Return code: 0 08:10:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:04 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 08:10:04 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 08:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:04 INFO - 'HOME': '/Users/cltbld', 08:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:04 INFO - 'LOGNAME': 'cltbld', 08:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:04 INFO - 'MOZ_NO_REMOTE': '1', 08:10:04 INFO - 'NO_EM_RESTART': '1', 08:10:04 INFO - 'PAGER': '/bin/cat', 08:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:04 INFO - 'PWD': '/builds/slave/test', 08:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:04 INFO - 'SHELL': '/bin/bash', 08:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:04 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:04 INFO - 'USER': 'cltbld', 08:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:04 INFO - 'XPC_FLAGS': '0x0', 08:10:04 INFO - 'XPC_SERVICE_NAME': '0', 08:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:05 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 08:10:05 INFO - Cleaning up... 08:10:05 INFO - Return code: 0 08:10:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:05 INFO - 'HOME': '/Users/cltbld', 08:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:05 INFO - 'LOGNAME': 'cltbld', 08:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:05 INFO - 'MOZ_NO_REMOTE': '1', 08:10:05 INFO - 'NO_EM_RESTART': '1', 08:10:05 INFO - 'PAGER': '/bin/cat', 08:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:05 INFO - 'PWD': '/builds/slave/test', 08:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:05 INFO - 'SHELL': '/bin/bash', 08:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:05 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:05 INFO - 'USER': 'cltbld', 08:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:05 INFO - 'XPC_FLAGS': '0x0', 08:10:05 INFO - 'XPC_SERVICE_NAME': '0', 08:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:05 INFO - Downloading/unpacking psutil>=3.1.1 08:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:08 INFO - Installing collected packages: psutil 08:10:08 INFO - Running setup.py install for psutil 08:10:08 INFO - building 'psutil._psutil_osx' extension 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 INFO - building 'psutil._psutil_posix' extension 08:10:08 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 08:10:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:10:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:10:08 INFO - ^ 08:10:09 INFO - 1 warning generated. 08:10:09 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 08:10:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:09 INFO - Successfully installed psutil 08:10:09 INFO - Cleaning up... 08:10:09 INFO - Return code: 0 08:10:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:09 INFO - 'HOME': '/Users/cltbld', 08:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:09 INFO - 'LOGNAME': 'cltbld', 08:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:09 INFO - 'MOZ_NO_REMOTE': '1', 08:10:09 INFO - 'NO_EM_RESTART': '1', 08:10:09 INFO - 'PAGER': '/bin/cat', 08:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:09 INFO - 'PWD': '/builds/slave/test', 08:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:09 INFO - 'SHELL': '/bin/bash', 08:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:09 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:09 INFO - 'USER': 'cltbld', 08:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:09 INFO - 'XPC_FLAGS': '0x0', 08:10:09 INFO - 'XPC_SERVICE_NAME': '0', 08:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:10:09 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 08:10:09 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 08:10:09 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 08:10:09 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 08:10:09 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 08:10:09 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 08:10:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:10:12 INFO - Installing collected packages: mozsystemmonitor 08:10:12 INFO - Running setup.py install for mozsystemmonitor 08:10:12 INFO - Successfully installed mozsystemmonitor 08:10:12 INFO - Cleaning up... 08:10:12 INFO - Return code: 0 08:10:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:12 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:12 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 08:10:12 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 08:10:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:12 INFO - 'HOME': '/Users/cltbld', 08:10:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:12 INFO - 'LOGNAME': 'cltbld', 08:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:12 INFO - 'MOZ_NO_REMOTE': '1', 08:10:12 INFO - 'NO_EM_RESTART': '1', 08:10:12 INFO - 'PAGER': '/bin/cat', 08:10:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:12 INFO - 'PWD': '/builds/slave/test', 08:10:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:12 INFO - 'SHELL': '/bin/bash', 08:10:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:12 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:12 INFO - 'USER': 'cltbld', 08:10:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:12 INFO - 'XPC_FLAGS': '0x0', 08:10:12 INFO - 'XPC_SERVICE_NAME': '0', 08:10:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:12 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 08:10:12 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 08:10:12 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 08:10:12 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 08:10:12 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 08:10:12 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 08:10:14 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:16 INFO - Downloading docopt-0.6.1.tar.gz 08:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:16 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:16 INFO - Running setup.py install for blobuploader 08:10:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:16 INFO - Running setup.py install for requests 08:10:16 INFO - Running setup.py install for docopt 08:10:17 INFO - Successfully installed blobuploader requests docopt 08:10:17 INFO - Cleaning up... 08:10:17 INFO - Return code: 0 08:10:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:17 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:17 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 08:10:17 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 08:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:17 INFO - 'HOME': '/Users/cltbld', 08:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:17 INFO - 'LOGNAME': 'cltbld', 08:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:17 INFO - 'MOZ_NO_REMOTE': '1', 08:10:17 INFO - 'NO_EM_RESTART': '1', 08:10:17 INFO - 'PAGER': '/bin/cat', 08:10:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:17 INFO - 'PWD': '/builds/slave/test', 08:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:17 INFO - 'SHELL': '/bin/bash', 08:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:17 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:17 INFO - 'USER': 'cltbld', 08:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:17 INFO - 'XPC_FLAGS': '0x0', 08:10:17 INFO - 'XPC_SERVICE_NAME': '0', 08:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:17 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-TrJHkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:17 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-IToxqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:17 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-H8_QSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:17 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-SfzOfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:17 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-vYndbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:17 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-0iD54J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-eSr177-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-8hF6br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-4fxf4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-9BBzka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Lu5UoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-c5TFwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MSzJG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-TpeYEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MXT54G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:18 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-mda3z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:19 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-xxI8Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:10:19 INFO - Running setup.py install for manifestparser 08:10:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:10:19 INFO - Running setup.py install for mozcrash 08:10:19 INFO - Running setup.py install for mozdebug 08:10:19 INFO - Running setup.py install for mozdevice 08:10:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:10:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:10:19 INFO - Running setup.py install for mozfile 08:10:19 INFO - Running setup.py install for mozhttpd 08:10:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Running setup.py install for mozinfo 08:10:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Running setup.py install for mozInstall 08:10:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Running setup.py install for mozleak 08:10:20 INFO - Running setup.py install for mozlog 08:10:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Running setup.py install for moznetwork 08:10:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:20 INFO - Running setup.py install for mozprocess 08:10:20 INFO - Running setup.py install for mozprofile 08:10:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:21 INFO - Running setup.py install for mozrunner 08:10:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:21 INFO - Running setup.py install for mozscreenshot 08:10:21 INFO - Running setup.py install for moztest 08:10:21 INFO - Running setup.py install for mozversion 08:10:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:21 INFO - Cleaning up... 08:10:21 INFO - Return code: 0 08:10:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:21 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')]} 08:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:21 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')]} 08:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:21 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:21 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 08:10:21 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 08:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:21 INFO - 'HOME': '/Users/cltbld', 08:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:21 INFO - 'LOGNAME': 'cltbld', 08:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:21 INFO - 'MOZ_NO_REMOTE': '1', 08:10:21 INFO - 'NO_EM_RESTART': '1', 08:10:21 INFO - 'PAGER': '/bin/cat', 08:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:21 INFO - 'PWD': '/builds/slave/test', 08:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:21 INFO - 'SHELL': '/bin/bash', 08:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:21 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:21 INFO - 'USER': 'cltbld', 08:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:21 INFO - 'XPC_FLAGS': '0x0', 08:10:21 INFO - 'XPC_SERVICE_NAME': '0', 08:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:21 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-26OzxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ioyQUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-h9Bbpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-8YjFyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-v5cd64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-9LTGFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-5Bh4vK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-qjFK2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-wfF7OM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:22 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)) 08:10:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:22 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Aiezzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-UzoxIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-VuqTBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-TrnqJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-z33qFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-FdK7GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Bl3JcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:23 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)) 08:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:23 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-cazrZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:23 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)) 08:10:23 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)) 08:10:23 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)) 08:10:23 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)) 08:10:23 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)) 08:10:23 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)) 08:10:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10:23 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 08:10:23 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 08:10:23 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 08:10:23 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 08:10:23 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 08:10:23 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 08:10:26 INFO - Downloading blessings-1.6.tar.gz 08:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:26 INFO - Installing collected packages: blessings 08:10:26 INFO - Running setup.py install for blessings 08:10:26 INFO - Successfully installed blessings 08:10:26 INFO - Cleaning up... 08:10:26 INFO - Return code: 0 08:10:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:26 INFO - Reading from file tmpfile_stdout 08:10:26 INFO - Current package versions: 08:10:26 INFO - blessings == 1.6 08:10:26 INFO - blobuploader == 1.2.4 08:10:26 INFO - docopt == 0.6.1 08:10:26 INFO - manifestparser == 1.1 08:10:26 INFO - mozInstall == 1.12 08:10:26 INFO - mozcrash == 0.17 08:10:26 INFO - mozdebug == 0.1 08:10:26 INFO - mozdevice == 0.48 08:10:26 INFO - mozfile == 1.2 08:10:26 INFO - mozhttpd == 0.7 08:10:26 INFO - mozinfo == 0.9 08:10:26 INFO - mozleak == 0.1 08:10:26 INFO - mozlog == 3.2 08:10:26 INFO - moznetwork == 0.27 08:10:26 INFO - mozprocess == 0.22 08:10:26 INFO - mozprofile == 0.28 08:10:26 INFO - mozrunner == 6.11 08:10:26 INFO - mozscreenshot == 0.1 08:10:26 INFO - mozsystemmonitor == 0.3 08:10:26 INFO - moztest == 0.7 08:10:26 INFO - mozversion == 1.4 08:10:26 INFO - psutil == 3.1.1 08:10:26 INFO - requests == 1.2.3 08:10:26 INFO - wsgiref == 0.1.2 08:10:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:26 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:26 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 08:10:26 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 08:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:26 INFO - 'HOME': '/Users/cltbld', 08:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:26 INFO - 'LOGNAME': 'cltbld', 08:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:26 INFO - 'MOZ_NO_REMOTE': '1', 08:10:26 INFO - 'NO_EM_RESTART': '1', 08:10:26 INFO - 'PAGER': '/bin/cat', 08:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:26 INFO - 'PWD': '/builds/slave/test', 08:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:26 INFO - 'SHELL': '/bin/bash', 08:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:26 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:26 INFO - 'USER': 'cltbld', 08:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:26 INFO - 'XPC_FLAGS': '0x0', 08:10:26 INFO - 'XPC_SERVICE_NAME': '0', 08:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 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)) 08:10:27 INFO - Cleaning up... 08:10:27 INFO - Return code: 0 08:10:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:27 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')]} 08:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:27 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')]} 08:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:27 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:27 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 08:10:27 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 08:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:27 INFO - 'HOME': '/Users/cltbld', 08:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:27 INFO - 'LOGNAME': 'cltbld', 08:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:27 INFO - 'MOZ_NO_REMOTE': '1', 08:10:27 INFO - 'NO_EM_RESTART': '1', 08:10:27 INFO - 'PAGER': '/bin/cat', 08:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:27 INFO - 'PWD': '/builds/slave/test', 08:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:27 INFO - 'SHELL': '/bin/bash', 08:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:27 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:27 INFO - 'USER': 'cltbld', 08:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:27 INFO - 'XPC_FLAGS': '0x0', 08:10:27 INFO - 'XPC_SERVICE_NAME': '0', 08:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:27 INFO - Downloading/unpacking jsonschema 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 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 08:10:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:29 INFO - Downloading/unpacking functools32 (from jsonschema) 08:10:29 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 08:10:29 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 08:10:29 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 08:10:29 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 08:10:29 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 08:10:29 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 08:10:30 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:30 INFO - warning: no files found matching '*.txt' 08:10:30 INFO - no previously-included directories found matching 'build' 08:10:30 INFO - no previously-included directories found matching 'dist' 08:10:30 INFO - no previously-included directories found matching '.git*' 08:10:30 INFO - Installing collected packages: jsonschema, functools32 08:10:30 INFO - Running setup.py install for functools32 08:10:30 INFO - warning: no files found matching '*.txt' 08:10:30 INFO - no previously-included directories found matching 'build' 08:10:30 INFO - no previously-included directories found matching 'dist' 08:10:30 INFO - no previously-included directories found matching '.git*' 08:10:30 INFO - Successfully installed jsonschema functools32 08:10:30 INFO - Cleaning up... 08:10:30 INFO - Return code: 0 08:10:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:30 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 0x103355a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10335b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbafbd7d0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, '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.EzAZoKS8YY/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.OdjKtstbwW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 08:10:30 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 08:10:30 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 08:10:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:30 INFO - 'HOME': '/Users/cltbld', 08:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:30 INFO - 'LOGNAME': 'cltbld', 08:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:30 INFO - 'MOZ_NO_REMOTE': '1', 08:10:30 INFO - 'NO_EM_RESTART': '1', 08:10:30 INFO - 'PAGER': '/bin/cat', 08:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:30 INFO - 'PWD': '/builds/slave/test', 08:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:30 INFO - 'SHELL': '/bin/bash', 08:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:10:30 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:30 INFO - 'USER': 'cltbld', 08:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:30 INFO - 'XPC_FLAGS': '0x0', 08:10:30 INFO - 'XPC_SERVICE_NAME': '0', 08:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:30 INFO - Downloading/unpacking flake8 08:10:30 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 08:10:30 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 08:10:30 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 08:10:30 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 08:10:30 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 08:10:30 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 08:10:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:10:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:10:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:33 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 08:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:10:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:34 INFO - no previously-included directories found matching 'docs/_build' 08:10:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:10:34 INFO - Running setup.py install for pep8 08:10:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:34 INFO - no previously-included directories found matching 'docs/_build' 08:10:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:10:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:10:34 INFO - Cleaning up... 08:10:34 INFO - Return code: 0 08:10:34 INFO - Running post-action listener: _resource_record_post_action 08:10:34 INFO - Running post-action listener: _start_resource_monitoring 08:10:34 INFO - Starting resource monitoring. 08:10:34 INFO - ##### 08:10:34 INFO - ##### Finished create-virtualenv step (success) 08:10:34 INFO - ##### 08:10:34 INFO - ##### 08:10:34 INFO - ##### Running install step. 08:10:34 INFO - ##### 08:10:34 INFO - Running pre-action listener: _resource_record_pre_action 08:10:34 INFO - Running main action method: install 08:10:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:35 INFO - Reading from file tmpfile_stdout 08:10:35 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:35 INFO - Reading from file tmpfile_stdout 08:10:35 INFO - Output received: 08:10:35 INFO - Usage: mozinstall [options] installer 08:10:35 INFO - Options: 08:10:35 INFO - -h, --help show this help message and exit 08:10:35 INFO - -d DEST, --destination=DEST 08:10:35 INFO - Directory to install application into. [default: 08:10:35 INFO - "/builds/slave/test"] 08:10:35 INFO - --app=APP Application being installed. [default: firefox] 08:10:35 INFO - mkdir: /builds/slave/test/build/application 08:10:35 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'] 08:10:35 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 08:11:09 INFO - Reading from file tmpfile_stdout 08:11:09 INFO - Output received: 08:11:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:11:09 INFO - Running post-action listener: _resource_record_post_action 08:11:09 INFO - ##### 08:11:09 INFO - ##### Finished install step (success) 08:11:09 INFO - ##### 08:11:09 INFO - ##### 08:11:09 INFO - ##### Running run-tests step. 08:11:09 INFO - ##### 08:11:09 INFO - Running pre-action listener: _resource_record_pre_action 08:11:09 INFO - Running main action method: run_tests 08:11:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:11:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:11:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:11:09 INFO - 2016-06-01 08:11:09.136 screenresolution[1923:14981] starting screenresolution argv=screenresolution get 08:11:09 INFO - 2016-06-01 08:11:09.138 screenresolution[1923:14981] Display 0: 1600x1200x32@60 08:11:09 INFO - 2016-06-01 08:11:09.144 screenresolution[1924:14983] starting screenresolution argv=screenresolution list 08:11:09 INFO - Available Modes on Display 0 08:11:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:11:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:11:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:11:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:11:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:11:09 INFO - Intel Iris: 08:11:09 INFO - Chipset Model: Intel Iris 08:11:09 INFO - Type: GPU 08:11:09 INFO - Bus: Built-In 08:11:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:11:09 INFO - Vendor: Intel (0x8086) 08:11:09 INFO - Device ID: 0x0a2e 08:11:09 INFO - Revision ID: 0x0009 08:11:09 INFO - Displays: 08:11:09 INFO - Display: 08:11:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:11:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:11:09 INFO - Display Serial Number: 1600x1200 60 08:11:09 INFO - Main Display: Yes 08:11:09 INFO - Mirror: Off 08:11:09 INFO - Online: Yes 08:11:09 INFO - Rotation: Supported 08:11:09 INFO - Return code: 0 08:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:09 INFO - Python 2.7.3 08:11:09 INFO - Return code: 0 08:11:09 INFO - grabbing minidump binary from tooltool 08:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:09 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 0x1031f9d30>, '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 0x10301ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1031f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:09 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 08:11:09 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 08:11:09 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 08:11:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:09 INFO - Return code: 0 08:11:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:11:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:11:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:11:10 INFO - Return code: 0 08:11:10 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/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 08:11:10 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/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --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 08:11:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EzAZoKS8YY/Render', 08:11:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:10 INFO - 'HOME': '/Users/cltbld', 08:11:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:10 INFO - 'LOGNAME': 'cltbld', 08:11:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:11:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:10 INFO - 'MOZ_NO_REMOTE': '1', 08:11:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:10 INFO - 'NO_EM_RESTART': '1', 08:11:10 INFO - 'PAGER': '/bin/cat', 08:11:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:10 INFO - 'PWD': '/builds/slave/test', 08:11:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:10 INFO - 'SHELL': '/bin/bash', 08:11:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OdjKtstbwW/Listeners', 08:11:10 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 08:11:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:10 INFO - 'USER': 'cltbld', 08:11:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:10 INFO - 'XPC_FLAGS': '0x0', 08:11:10 INFO - 'XPC_SERVICE_NAME': '0', 08:11:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:10 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/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 08:11:10 INFO - mozversion application_buildid: 20160601060544 08:11:10 INFO - mozversion application_changeset: 78e2125dfb6b02cdbab73a30c54bb4dc19aa310a 08:11:10 INFO - mozversion application_display_name: Nightly 08:11:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:10 INFO - mozversion application_name: Firefox 08:11:10 INFO - mozversion application_remotingname: firefox 08:11:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:11:10 INFO - mozversion application_vendor: Mozilla 08:11:10 INFO - mozversion application_version: 49.0a1 08:11:10 INFO - mozversion platform_buildid: 20160601060544 08:11:10 INFO - mozversion platform_changeset: 78e2125dfb6b02cdbab73a30c54bb4dc19aa310a 08:11:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:11:10 INFO - mozversion platform_version: 49.0a1 08:11:10 INFO - using testdate: 1464793870 08:11:10 INFO - actual date: 1464793870 08:11:10 INFO - starting webserver on 'localhost:51907' 08:11:10 INFO - SUITE-START | Running 2 tests 08:11:10 INFO - TEST-START | dromaeo_css 08:11:10 INFO - operating with platform_type : mac_ 08:11:10 INFO - Initialising browser for dromaeo_css test... 08:11:10 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpj8bX5K/profile http://localhost:51907/getInfo.html) 08:11:13 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 08:11:13 INFO - PROCESS | 1942 | colorDepth:24 08:11:13 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 08:11:13 INFO - PROCESS | 1942 | __metrics 08:11:13 INFO - PROCESS | 1942 | 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" 08:11:13 ERROR - PROCESS | 1942 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:11:13 INFO - TEST-INFO | 1942: exit 0 08:11:13 INFO - Browser initialized. 08:11:13 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:13 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpj8bX5K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:25 INFO - PROCESS | 1944 | RSS: Main: 241725440 08:11:25 INFO - PROCESS | 1944 | 08:12:17 INFO - PROCESS | 1944 | 1464793937504 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] 08:14:28 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51907/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51907/tests/dromaeo/cssquery-ext.html) 08:14:29 INFO - PROCESS | 1944 | RSS: Main: 335740928 08:14:29 INFO - PROCESS | 1944 | 08:17:32 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51907/tests/dromaeo/cssquery-ext.html (next: http://localhost:51907/tests/dromaeo/cssquery-jquery.html) 08:17:33 INFO - PROCESS | 1944 | RSS: Main: 378601472 08:17:33 INFO - PROCESS | 1944 | 08:20:35 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51907/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51907/tests/dromaeo/cssquery-mootools.html) 08:20:36 INFO - PROCESS | 1944 | RSS: Main: 376786944 08:20:36 INFO - PROCESS | 1944 | 08:23:38 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51907/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51907/tests/dromaeo/cssquery-prototype.html) 08:23:38 INFO - PROCESS | 1944 | RSS: Main: 367599616 08:23:38 INFO - PROCESS | 1944 | 08:26:42 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51907/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51907/tests/dromaeo/cssquery-yui.html) 08:26:43 INFO - PROCESS | 1944 | RSS: Main: 339755008 08:26:43 INFO - PROCESS | 1944 | 08:29:45 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:51907/tests/dromaeo/cssquery-yui.html (next: http://localhost:51907/tests/dromaeo/cssquery-dojo.html) 08:29:46 INFO - PROCESS | 1944 | RSS: Main: 388157440 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | __start_tp_report 08:29:46 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 08:29:46 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 08:29:46 INFO - PROCESS | 1944 | |i|pagename|runs| 08:29:46 INFO - PROCESS | 1944 | |0;dojo.html;3375.527426160338;3411.2495251044775;3420.7092397146243;3440.8107554084513;3486.686198242158;5588.497035266825;5623.943760562393;5687.459691329327;5687.8578035549135;5715.79994700185;4801.679538127017;5559.19391688205;5691.914621280674;5761.049426844576;5787.50806181474;5055.5702765265;5062.924056139161;5096.821611243588;5100.642954993134;5225.372955245376;5234.188700751371;5269.499397557226;5274.57803375731;5280;5312.707801070939;5080.517350851663;5087.135187018216;5140.74296285188;5146.639735218536;5193.58451323895;5066.468020857794;5084.872878178038;5103.209002604565;5114.641975061747;5166.4575219601775;5061.924071138936;5132.127538318495;5155.7679904404395;5156.74216289184;5194.35070616173;5524.99999999996;5566.972165139189;5579.302587176603;5627.296587926509;5646.971765141148;5219.71291578963;6163.3220345762375;6168.383161683841;6190.195274614316;6198.008318669036;5479.671219726829;6144.692765361759;6161.4146656067605;6168.537359697995;6175;5630.774769009235;5654.180143879114;5719.170720245625;5721.713914304268;5791.420857914175;6241.500679945593;6246.500279977591;6249.125122482856;6258.030005349178;6265.906011409833;6077.799012581625;6080.662873115721;6084.202256465377;6088.756449741961;6128.2033335666965;6026.879462410728;6061.787837425669;6128.233970753656;6149;6183.474404675653;6120.908186377208;6126.792505495304;6130.111133885562;6137.631742095488;6151.861905547488;6120.489360537716;6171.444569988723;6172.753089876354;6173.56785025044;6181.320054793969;4854.927176092361;4855.684380515255;4925.630577706686;4950.777215025332;4964.478729733326;4389.758563279051;4466.0398014427055;4486.102779444124;4499.0776890737325;4499.437570303712;5567;5635.830925072254;5662.886742265132;5703.515201207861;5732.484076433141;6153.476954458832;6190.288116866528;6218.875622487525;6226.128342032119;6242.375762423812;5650.576206784425;5662.037453632893;5668.036433806263;5669.489745922887;5672.858178545487;5242.7378631068295;5291.920621190685;5309.628326017141;5343.064963631349;5363.383210930793;6244.313125556185;6281.15204447412;6295.307516173218;6298.118263443122;6298.559100862895;6160.798644264505;6178.165947597017;6195.81412557624;6213.751449941951;6221.626702397871;6137.683438940172;6189.84525386869;6192.752289908533;6204.565680402328;6254.468370188403;6098.664573448503;6104.877902441837;6105.43490155904;6130.969345153246;6139.877202456015;6120.723104933873;6181.721822517908;6189.659568723763;6190.41191086846;6197.070439434121;5913.319968203712;5971.880562388641;5980.551458640706;6011.368806275453;6018.849528761641;5943.108533719977;6015.608985415934;6020.819375418744;6027.728752206249;6047;5686.203931449518;5715.257016587817;5832.9708351457975;5863.530917526502;5877.911831322619;5989.730462129128;6017.518598512021;6018.976773948351;6043.153958445733;6054.788082417271;5850.736716847837;5851.941480585139;5853.209816674695;5857.502112320501;5885.499732522783 08:29:46 INFO - PROCESS | 1944 | |1;ext.html;489.32961842276086;489.88977480066984;516.7132241605909;523.7119584228675;534.078714217974;23914.641280380787;42559.36160957584;43225.78387108068;43427.565724342785;45330.54669453305;44586.33120503195;44942.55057449424;45018;45042.77478612611;45213.32180017302;9539.47532885691;9761.316707830454;9765.853512197322;9770.462624555646;9779.657711980082;9577.952110239474;9716.417014979124;9727.27045471589;9736.172425343855;9757.658481953133;9700.369475984075;9714.708558743248;9724.611015559369;9730.075642813958;9761.560729767141;9662.08210220028;9697.951510242403;9723.46520941348;9727.756806079833;9728.854067188999;9030.774230644221;9173.990861005284;9635.470049147294;9747.317687761859;9773.706788796348;41161;41546.58453415457;41580.37629435561;41598.96002599929;42358;3409.829508524564;3632.691221246197;3841.2701586698436;3954.912399090273;4502.684812063157;31476.527852082236;31835.04494865158;32113;32385.35229295401;32415.837920810245;3252.284497410566;3277.7541684373527;3369.2082360645227;3397.167693914982;3443.793372397639;483505;486898;488003;488005;488359;61874.76250475055;62265.68867155635;62590;62631.06053409202;62848.68575657184;65558.67220663866;65640;65718;65762.67118664471;65986.67006664936;16762.994220346573;16971.896826706223;16975.575610609587;16985.23566439513;17012.744808827876;13050.608481745563;13130.803037954438;13154;13168;13186.142900711424;15881;16171.272292746855;16179;16204.189790510427;16888.408905688488;6554.934450655528;6602.2407423146;6645.84010196688;6741.2921643226755;6758.628275444898;720.3161225412209;821.3470291118456;869.5000374784398;875.9912400876037;1025.4052649463292;30546;30563;30914;31284.217894552818;31547.842260788548;998.5620706183073;1001.6694490818013;1019.938803671782;1089.7765957978595;1176.4823477669797;15859.682806343808;16046;16047.919760401357;16627.757587270367;16710.6657866842;624840;628199;635277;638413;641731;60664.39335606588;60842.69578652167;60912.69543652253;61053.694731526055;61092;1266.170658218868;1272.0904553244475;1400.509821562445;1422.8434872164053;1446.3708286895235;30894.53658195174;31125;31182;31758.523622145225;31877.840610796797;50489.49510504995;56941.43058569527;58231.70884145552;58404.4159558399;60742.78514429775;572639;579823;584408;584767;586237;22997.770022299563;23790;26438.603420948315;26502;26924.730752692223;45848.312275316566;47854.760726196146;48013.27980080232;48623;49598.50401496083;40125.799371002955;40389.1922161561;40555.391669124394;41549.58450415457;41667.7916610415;39397.80301098476;40092.1981560373;40421;40495.79752101221;40526.79736601298;38746.80626596849;38939.80530097331;39192.020199495244;39501.20997579975;39684;34696.306073878884;34700.47949280712;35586.46620300749;35790;36542.086447839014;22013.779862201813;25684.871575642002;25726.742732573184;26350.86824565865;26428.471430571666 08:29:46 INFO - PROCESS | 1944 | |2;jquery.html;387.1135100619582;388.7647972976349;389.67460212558694;391.528208508747;412.86788227767124;13385.495262378601;19498.317558885443;19959.700604490943;20588.794112058877;20615.896920515377;20121.29575464859;20498;21072.57854842902;21418.57162856742;21466.677999830015;5739.856503587402;6011.008183649693;6013.1581578579035;6019.879602407949;6021.157038014681;5714.657120572758;5848.327442344121;5892.880352732967;5977.910331345033;6676.332366763333;6634.436072933807;6652.06871038055;6654.367835055662;6656.13470248867;6657.633830139341;5632.5212356949705;6337.112804207414;6514.902276465857;6524.021396790496;6547.345265473462;5386.946130538723;5586.1062230043;5606.971965140148;5610.831675049755;5616.1014237721765;24578.508429831483;24996.750032499807;25025.749742502707;25641.102561410262;25856.095036673814;2190.255313193506;2300.378897697612;2369.7037870266217;2690.515707172708;2708.146933715893;15260.236988150658;15297.388104475807;15480.45818396362;15547.689046219013;15674;1810.5021119192195;1897.127321432143;1907.7996810334853;1918.808119188084;1932.053293886;108716.45641771819;111649.44175279072;112391.43804281008;112477.43761281141;114713;8669.349798764992;10383.376997380181;10768.154330551284;10805.891941080488;10895.455227238606;12049.216800907912;12072.879271207352;12724.809127863089;13486.05597608158;13509.189448633113;7441.590712510756;8545.529995850164;8595.484270943763;8694.391392602458;8702.651893924172;8673.826523469495;8685.565721714016;8705.738827835175;8792.47245165292;8829.293656507463;6446;6557.868842623217;6566.080748695186;6566.5403421760975;6567.408933196035;1510.3203558398764;1822.8086050964723;1826.643804458118;1831.8259765322273;1858.8048254933308;900.6847603338779;949.6230465824582;955.0926619711302;973.7176218896441;974.512743628186;16754.497365079067;18580.907095464707;20862.165513379598;21224;21586.136554537643;929.9488528131018;977.7462947905005;979.2019504104132;993.3245393132595;1073.8228192348174;11249.04383127427;11340.262882912584;11468.426578671033;11691.590794322328;11720.706982325339;545740.2712986489;548227;557884;558088;569896;110021.44989275002;113407;113767;114532.42733786278;115544;991.5736233419699;1021.8262895307666;1028.28534168752;1033.9689809305733;1037.9740506487246;19031.52421189426;19272;19272.421827345202;19299.324523641604;19328.516787080436;46741.76629116833;46803.765981169876;47394.05211895812;48666.75666621644;49007.754961224964;69744.30255697378;70423.64788176026;70619;71025.28974710187;71629;7795.765877957659;7844.450888437983;7883.7240696575345;7899.763007109796;8069.991251093613;12453.75092498163;12568.560100396442;12608.117431779687;12611.117221794386;12677.429515671633;10650.45641248318;10816.66948402575;10822.675319740105;10835.566577336818;10860.346331132429;10010;10408.375497470177;10438.269321147447;10457.215708822021;10939.671809845717;10167.7458063549;10899.346039237671;11020.669379918616;11082.501287441923;11126.276792008817;7754.728584499516;7848.568328741974;7909.327707144727;7950.165232650488;8059.556724380215;10517.894821051692;10777.407242601732;10796.244262901522;10914;10927.07119894786 08:29:46 INFO - PROCESS | 1944 | |3;mootools.html;1401.7266633006554;1490.5528341497547;1498.4006397441021;1779.937702180424;1963.9312624058161;7794.220577942203;8544.743657690271;8731.17053879882;8750.649974001033;8879.422837515556;8457.746267611967;8701.86947195791;8704.73885783426;8704.73885783426;8707.521086340248;2080.843936704746;2156.773538778429;2158.6006588781124;2316.3977365884907;2359.634256690216;2104.968425473619;2123.182574708733;2316.0967222783042;2390.3187591536416;2391.7727815857465;1982.7224188613607;2356.1046802215133;2364.5507353602848;2368.7749663782;2369.490559529701;2095.298075144828;2161.4056134562966;2255.8533695309834;2299.9310020699404;2306.9468173325754;2092.7384076990375;2297.5864344417996;2358.1392791630933;2362.2795047510504;2377.833551651368;8717.171868672465;8788.20906118446;8825.955870220672;8831.77920551985;8842.955785221096;5159.509846564571;5325;5329.920051199235;5330.147176451788;5333.066713325192;3515.82420878955;3637.4907512948207;3709.443583462475;3713.3130370881204;3714.8699795507296;1068.4978060311641;1096.802575536403;1106.3859557945336;1120.9047230985377;1121.6410748560463;11568.768624627544;11587.246828956175;11592.507610863704;11598.652040438801;11609.941950290278;4559.794809233593;4719.174144524694;4750.192467280502;4789.425268967746;4790.77835152034;9842.901570984342;9919.256055795844;9919.80160396788;9920.553575089138;9923;5274.604404669665;5277.340332458442;5285.497877701612;5286.048511268009;5296.576273898079;4455.68810183284;4704.976475117671;4790.137775200497;4856.635752318591;4860.80556777732;3586.7130629549574;3683.963160368362;3684.0605645560745;3688.6864616507896;3697.6302369762807;1158.808796548577;1166.1720178673067;1179.7522520270838;1181.7341098252825;1185.709501172201;809.9919000809916;843.5276245302528;894.789724414762;894.9642014319485;967.3734915826672;5820.679862607597;5826.854328641818;6052.939470605325;6071.2714474263375;6138.171346868205;917.1974522292994;964.7298756348234;965.0783501755807;972.5672075926145;1013.2451323763843;3988.501437320335;4015;4047.6964227683043;4065.4714887064492;4123.113530590937;10530.262881598215;10816.296940698829;10892.40091794959;10904.563817447372;10904.672859814218;10750.462476876124;10848.67453976382;10853.891461085446;10856.294340867818;10859.13126949842;7779.222077792176;8089.231523005187;8136.918630813734;8182.918170818335;8284.8343033139;6080.331163571915;6089.5737298388685;6159.476444502267;6176.969115154396;6211.440970312694;9894.356866803635;10123;10184.439855808001;10208.693739187836;10209.489525523546;7895.289423951872;9928.751781205528;10419.895801041892;10440.68677939663;10444.791104178026;7484.70061197546;7666.501677390952;7742.303192712683;7746.806329841799;7754.534727916343;8266.834663306821;8274.669013239403;8289.088200297841;8301.335893128657;8400.075991640793;7561.09266887977;7582.203868593719;7602.353799927068;7669.654865531176;7698.769036928901;7372.262773722585;7379.594122323324;7387.519811212254;7514;7538.057742782153;6893.310668933267;6985.615791131537;7059.788206353817;7096.929030709834;7108.857822843617;5005.149124648746;5009.248612707977;5020.748962551785;5149.0474262261605;5155.484451554815;4474.821007159677;4914.557689807934;4977.377827771528;5069.619778516552;5095.082885080721 08:29:46 INFO - PROCESS | 1944 | |4;prototype.html;308.23865053318303;310.6986223363337;316.20159098276844;317.61251273446385;321.2482790270766;4522.706024108431;4855.732934688591;4970.155073637481;5194.5324920757075;5318.76065577049;4120.299549076657;5052.772625231864;5176.197689358156;5182.248573956774;5232.973835130829;3270.3622793555264;3685.4471829225695;3826.9043273918237;3864.845406183749;3889.183271512986;3601.6769183876386;3745.9250814983684;3792.6207379262128;3894.240623078501;3920.882373528799;3358.9832050839714;3484.9303013939834;3577.3365385095926;3774.811259437031;3954.7231693781446;3445.4659527773233;3903.882883513485;3918.1967696622123;3944.447777311179;3958.5447673517624;3261.249912520134;3381.425157723193;3389.1357703785625;3428.8457019434186;3437.295354452331;4318.775830447322;4667.719936803803;4925.876853078666;5017.7491125443585;5039.21892106724;3902.5902280260448;4067.8983025424304;4528;4535.455745310584;4561.703489273186;5091.1854103343285;5284.180951952453;5301.019311427398;5516.800255851596;5529.157235721175;3252.650150187693;3822.598627144165;3826.1773718650347;3829.272438236754;3843.615638436134;4754.857354279377;5483.7516618186355;5636.267285252891;5707.200991861143;5709.743061562198;3336.966630333714;3724.845297957642;3749.306378319965;3854.5181852268465;3856.36369998947;3080.1683545442606;3500.949715085464;3923.5085720567645;3948.506436695413;3976.1053762903116;4043.332850079763;4240.38514415408;4383.364412160219;4507.706999045117;4555.772211389417;4400.405945197422;5524.281843360418;5531;5534.418886016911;5550.72246387679;1099.444780385916;1216.3370962825281;1336.8930485561132;1344.126317893377;1345.7779466387935;725.7242247945746;798.9320907722908;826.5454000299811;829.5022986208347;851.5997481183833;3719.5350581177354;3971.4241434991764;3973.523177218752;4111.444954931106;4360.345948107809;4289.549597292302;4569.514907655025;4735.95264047362;4842.225243960949;4860.805567777249;3861.5559210690567;3865.7100717446306;4200.768957707355;4310.331898555729;4360.585744354281;2468.987655061749;2627.316897606636;2748.175547335831;2760.30992251937;2895.4643390972738;5455.263144745862;5592.692401917852;5889.558283128783;5914.645121292736;5919.289685237799;5624.325080990226;5806.477417032487;5816.970915145397;5857.824265272048;5858.707064646751;5459.372172200168;5860.296764388216;5871.73577189036;5925.34821169671;5946.316173640001;5616.606837521498;5639.746211420414;5668.372241765356;5727.427257274239;5743;4655.604273636779;4979.825706100414;5378.542823860016;5432.59255555827;5626.943730562805;4832.468428472936;4967.9006419870675;5237.685738855681;5867.911981320198;5908.852278693067;3680.4663323817363;3795.6963442925053;3800.942985855159;3805.3911374179997;3805.7631269837966;4773.236282194832;5407.458874220828;5698;5755.510781583444;5790.420957904176;4593.127305811885;4901.852944411673;5452.781888724407;5453.427390124139;5479.205515200194;4850.563449289581;5137.17805151174;5385.973070134624;5455.972720136374;5484.341878974389;4651.7441540714235;4825.638077144158;4867.3915760529935;5394.811181608782;5428.321459817523;5469.343678758574;5479.342478902558;5486.369067557122;5531.262025297504;5556.333240011063;4562.498125206291;4837.572915989721;5360.2227676987395;5383.81156659515;5427.972860135674 08:29:46 INFO - PROCESS | 1944 | |5;yui.html;714.5177005521274;716.8673795347861;720.1682057223908;826.7726375246806;845.9365547583933;14554.272286385678;15258.618534536643;15265.542033738991;15277.006994545362;15283;13931.860681393184;14008.64978375541;14018.228997405138;14050.578482645511;14142.222177780219;712.2343480758179;736.3483317264233;888.5424006636588;889.8353804546148;905.8550631898897;758.4463341760518;914.6204325205032;915.0209276074603;915.4232833315002;915.8168366326728;945.5792172483248;962.1244667352711;962.2398305338809;963.9228861691083;964.3297907953975;747.4431548496351;935.1536859142487;955.7371722776222;961.27423795035;969.1713584884892;720.4416577152696;720.9963950180268;721.0410154494509;721.7257442171993;723.6671131279618;14390.496332628307;14432.278386080654;14446.566603001927;14449.566513004627;14518.491852785102;13052.673683157902;13158.342082895913;13358.599242022754;13537.390817413141;13625.114367566175;12043.277403355827;12174;12343.75312493754;12482.126251162423;12793.232406055573;4530.6602004849765;4542.2051141050515;4549.431321084864;4733.3846599942135;4735.976320118411;74873.62563187149;75202;75448.86826697605;76141.6192919043;77034.61482692607;10636.41499717523;10722.356658600509;10727.463626818628;10737;10768.623098191525;20303.796962030487;20304.59390812205;20311.593768124556;20318.187272508934;20331.796682033288;7782;7851.371890248881;7907.9604601976625;7955.920440795634;8037.156098609678;4414.823407063682;4415.757133357696;4426.867193984185;4606.769661516911;4609.377734005866;3356.9496457553155;3454.6545345465756;3457.6369481204615;3457.6369481204615;3461.169319363385;643.5334382572692;652.5073569455031;671.1141293492543;682.8839097353462;688.277308825731;541.5261646059698;597.8654802669365;605.2918085839578;644.5025526086415;658.6673729766437;8354.791130221793;8661.13388661129;8868.600912958933;8931.374803763803;9172.8624070639;622.4615707413145;634.2230767310008;649.9360962222453;657.7278041742294;668.7425341243656;4210.431591735138;4220.894477638084;4294.549072347359;4359.455068116486;4434.357018232403;73505.89741153887;83383;86834.56582717173;89681;90092;74688.62655686795;78470.6076469614;79413.60293198613;79585.20414795777;80879;70077.29922700708;70491.64754176197;70501;70513.64743176251;70715.64642176757;8357.540335281376;8367.748967530984;8370.958145209235;8579.598673809734;8630.870536941826;46287;46315;46549.53450465587;46658.300125497466;47299.05401892012;59249.11126333195;59480.10779838393;60843.69578152081;62589.37410625836;63141.421464463754;11137.164712646745;11274.323540587591;11374.829377559177;11449.656510304705;11868.940655296668;9833.4099954003;11783.587574434854;11789;12284.0786940978;12400.56598019065;11387.43062846871;11410.315381077162;11599.188056835941;11908.702282443008;12011.81982270284;9471.431714097176;10413.077486683096;10864.185186110915;10866.239363244496;10984.771270751105;10175.035185442124;10804.24370294072;11292.040176585082;11831.349275789915;11907.106966977684;8135.552544610104;9706.417614943126;9755.390505513005;9783.706488805346;9864.802703946025;8594.484330939913;8788.45819572402;9567.37812042215;9629.711108666752;9882.950585247028 08:29:46 INFO - PROCESS | 1944 | __end_tp_report 08:29:46 INFO - PROCESS | 1944 | __start_cc_report 08:29:46 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1239 08:29:46 INFO - PROCESS | 1944 | __end_cc_report 08:29:46 INFO - PROCESS | 1944 | __startTimestamp1464794986243__endTimestamp 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | ------- Summary: start ------- 08:29:46 INFO - PROCESS | 1944 | Number of tests: 6 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5675.15 Median:5853.21 stddev:615.66 (10.5%) stddev-sans-first:590.52 08:29:46 INFO - PROCESS | 1944 | Values: 3375.5 3411.2 3420.7 3440.8 3486.7 5588.5 5623.9 5687.5 5687.9 5715.8 4801.7 5559.2 5691.9 5761.0 5787.5 5055.6 5062.9 5096.8 5100.6 5225.4 5234.2 5269.5 5274.6 5280.0 5312.7 5080.5 5087.1 5140.7 5146.6 5193.6 5066.5 5084.9 5103.2 5114.6 5166.5 5061.9 5132.1 5155.8 5156.7 5194.4 5525.0 5567.0 5579.3 5627.3 5647.0 5219.7 6163.3 6168.4 6190.2 6198.0 5479.7 6144.7 6161.4 6168.5 6175.0 5630.8 5654.2 5719.2 5721.7 5791.4 6241.5 6246.5 6249.1 6258.0 6265.9 6077.8 6080.7 6084.2 6088.8 6128.2 6026.9 6061.8 6128.2 6149.0 6183.5 6120.9 6126.8 6130.1 6137.6 6151.9 6120.5 6171.4 6172.8 6173.6 6181.3 4854.9 4855.7 4925.6 4950.8 4964.5 4389.8 4466.0 4486.1 4499.1 4499.4 5567.0 5635.8 5662.9 5703.5 5732.5 6153.5 6190.3 6218.9 6226.1 6242.4 5650.6 5662.0 5668.0 5669.5 5672.9 5242.7 5291.9 5309.6 5343.1 5363.4 6244.3 6281.2 6295.3 6298.1 6298.6 6160.8 6178.2 6195.8 6213.8 6221.6 6137.7 6189.8 6192.8 6204.6 6254.5 6098.7 6104.9 6105.4 6131.0 6139.9 6120.7 6181.7 6189.7 6190.4 6197.1 5913.3 5971.9 5980.6 6011.4 6018.8 5943.1 6015.6 6020.8 6027.7 6047.0 5686.2 5715.3 5833.0 5863.5 5877.9 5989.7 6017.5 6019.0 6043.2 6054.8 5850.7 5851.9 5853.2 5857.5 5885.5 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:70884.45 Median:42892.57 stddev:152342.31 (355.2%) stddev-sans-first:152677.99 08:29:46 INFO - PROCESS | 1944 | Values: 489.3 489.9 516.7 523.7 534.1 23914.6 42559.4 43225.8 43427.6 45330.5 44586.3 44942.6 45018.0 45042.8 45213.3 9539.5 9761.3 9765.9 9770.5 9779.7 9578.0 9716.4 9727.3 9736.2 9757.7 9700.4 9714.7 9724.6 9730.1 9761.6 9662.1 9698.0 9723.5 9727.8 9728.9 9030.8 9174.0 9635.5 9747.3 9773.7 41161.0 41546.6 41580.4 41599.0 42358.0 3409.8 3632.7 3841.3 3954.9 4502.7 31476.5 31835.0 32113.0 32385.4 32415.8 3252.3 3277.8 3369.2 3397.2 3443.8 483505.0 486898.0 488003.0 488005.0 488359.0 61874.8 62265.7 62590.0 62631.1 62848.7 65558.7 65640.0 65718.0 65762.7 65986.7 16763.0 16971.9 16975.6 16985.2 17012.7 13050.6 13130.8 13154.0 13168.0 13186.1 15881.0 16171.3 16179.0 16204.2 16888.4 6554.9 6602.2 6645.8 6741.3 6758.6 720.3 821.3 869.5 876.0 1025.4 30546.0 30563.0 30914.0 31284.2 31547.8 998.6 1001.7 1019.9 1089.8 1176.5 15859.7 16046.0 16047.9 16627.8 16710.7 624840.0 628199.0 635277.0 638413.0 641731.0 60664.4 60842.7 60912.7 61053.7 61092.0 1266.2 1272.1 1400.5 1422.8 1446.4 30894.5 31125.0 31182.0 31758.5 31877.8 50489.5 56941.4 58231.7 58404.4 60742.8 572639.0 579823.0 584408.0 584767.0 586237.0 22997.8 23790.0 26438.6 26502.0 26924.7 45848.3 47854.8 48013.3 48623.0 49598.5 40125.8 40389.2 40555.4 41549.6 41667.8 39397.8 40092.2 40421.0 40495.8 40526.8 38746.8 38939.8 39192.0 39501.2 39684.0 34696.3 34700.5 35586.5 35790.0 36542.1 22013.8 25684.9 25726.7 26350.9 26428.5 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:33021.18 Median:2335.04 stddev:92542.21 (3963.2%) stddev-sans-first:92769.37 08:29:46 INFO - PROCESS | 1944 | Values: 387.1 388.8 389.7 391.5 412.9 13385.5 19498.3 19959.7 20588.8 20615.9 20121.3 20498.0 21072.6 21418.6 21466.7 5739.9 6011.0 6013.2 6019.9 6021.2 5714.7 5848.3 5892.9 5977.9 6676.3 6634.4 6652.1 6654.4 6656.1 6657.6 5632.5 6337.1 6514.9 6524.0 6547.3 5386.9 5586.1 5607.0 5610.8 5616.1 24578.5 24996.8 25025.7 25641.1 25856.1 2190.3 2300.4 2369.7 2690.5 2708.1 15260.2 15297.4 15480.5 15547.7 15674.0 1810.5 1897.1 1907.8 1918.8 1932.1 108716.5 111649.4 112391.4 112477.4 114713.0 8669.3 10383.4 10768.2 10805.9 10895.5 12049.2 12072.9 12724.8 13486.1 13509.2 7441.6 8545.5 8595.5 8694.4 8702.7 8673.8 8685.6 8705.7 8792.5 8829.3 6446.0 6557.9 6566.1 6566.5 6567.4 1510.3 1822.8 1826.6 1831.8 1858.8 900.7 949.6 955.1 973.7 974.5 16754.5 18580.9 20862.2 21224.0 21586.1 929.9 977.7 979.2 993.3 1073.8 11249.0 11340.3 11468.4 11691.6 11720.7 545740.3 548227.0 557884.0 558088.0 569896.0 110021.4 113407.0 113767.0 114532.4 115544.0 991.6 1021.8 1028.3 1034.0 1038.0 19031.5 19272.0 19272.4 19299.3 19328.5 46741.8 46803.8 47394.1 48666.8 49007.8 69744.3 70423.6 70619.0 71025.3 71629.0 7795.8 7844.5 7883.7 7899.8 8070.0 12453.8 12568.6 12608.1 12611.1 12677.4 10650.5 10816.7 10822.7 10835.6 10860.3 10010.0 10408.4 10438.3 10457.2 10939.7 10167.7 10899.3 11020.7 11082.5 11126.3 7754.7 7848.6 7909.3 7950.2 8059.6 10517.9 10777.4 10796.2 10914.0 10927.1 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5716.89 Median:4991.26 stddev:3222.98 (64.6%) stddev-sans-first:3215.70 08:29:46 INFO - PROCESS | 1944 | Values: 1401.7 1490.6 1498.4 1779.9 1963.9 7794.2 8544.7 8731.2 8750.6 8879.4 8457.7 8701.9 8704.7 8704.7 8707.5 2080.8 2156.8 2158.6 2316.4 2359.6 2105.0 2123.2 2316.1 2390.3 2391.8 1982.7 2356.1 2364.6 2368.8 2369.5 2095.3 2161.4 2255.9 2299.9 2306.9 2092.7 2297.6 2358.1 2362.3 2377.8 8717.2 8788.2 8826.0 8831.8 8843.0 5159.5 5325.0 5329.9 5330.1 5333.1 3515.8 3637.5 3709.4 3713.3 3714.9 1068.5 1096.8 1106.4 1120.9 1121.6 11568.8 11587.2 11592.5 11598.7 11609.9 4559.8 4719.2 4750.2 4789.4 4790.8 9842.9 9919.3 9919.8 9920.6 9923.0 5274.6 5277.3 5285.5 5286.0 5296.6 4455.7 4705.0 4790.1 4856.6 4860.8 3586.7 3684.0 3684.1 3688.7 3697.6 1158.8 1166.2 1179.8 1181.7 1185.7 810.0 843.5 894.8 895.0 967.4 5820.7 5826.9 6052.9 6071.3 6138.2 917.2 964.7 965.1 972.6 1013.2 3988.5 4015.0 4047.7 4065.5 4123.1 10530.3 10816.3 10892.4 10904.6 10904.7 10750.5 10848.7 10853.9 10856.3 10859.1 7779.2 8089.2 8136.9 8182.9 8284.8 6080.3 6089.6 6159.5 6177.0 6211.4 9894.4 10123.0 10184.4 10208.7 10209.5 7895.3 9928.8 10419.9 10440.7 10444.8 7484.7 7666.5 7742.3 7746.8 7754.5 8266.8 8274.7 8289.1 8301.3 8400.1 7561.1 7582.2 7602.4 7669.7 7698.8 7372.3 7379.6 7387.5 7514.0 7538.1 6893.3 6985.6 7059.8 7096.9 7108.9 5005.1 5009.2 5020.7 5149.0 5155.5 4474.8 4914.6 4977.4 5069.6 5095.1 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4328.05 Median:4701.84 stddev:1364.33 (29.0%) stddev-sans-first:1334.38 08:29:46 INFO - PROCESS | 1944 | Values: 308.2 310.7 316.2 317.6 321.2 4522.7 4855.7 4970.2 5194.5 5318.8 4120.3 5052.8 5176.2 5182.2 5233.0 3270.4 3685.4 3826.9 3864.8 3889.2 3601.7 3745.9 3792.6 3894.2 3920.9 3359.0 3484.9 3577.3 3774.8 3954.7 3445.5 3903.9 3918.2 3944.4 3958.5 3261.2 3381.4 3389.1 3428.8 3437.3 4318.8 4667.7 4925.9 5017.7 5039.2 3902.6 4067.9 4528.0 4535.5 4561.7 5091.2 5284.2 5301.0 5516.8 5529.2 3252.7 3822.6 3826.2 3829.3 3843.6 4754.9 5483.8 5636.3 5707.2 5709.7 3337.0 3724.8 3749.3 3854.5 3856.4 3080.2 3500.9 3923.5 3948.5 3976.1 4043.3 4240.4 4383.4 4507.7 4555.8 4400.4 5524.3 5531.0 5534.4 5550.7 1099.4 1216.3 1336.9 1344.1 1345.8 725.7 798.9 826.5 829.5 851.6 3719.5 3971.4 3973.5 4111.4 4360.3 4289.5 4569.5 4736.0 4842.2 4860.8 3861.6 3865.7 4200.8 4310.3 4360.6 2469.0 2627.3 2748.2 2760.3 2895.5 5455.3 5592.7 5889.6 5914.6 5919.3 5624.3 5806.5 5817.0 5857.8 5858.7 5459.4 5860.3 5871.7 5925.3 5946.3 5616.6 5639.7 5668.4 5727.4 5743.0 4655.6 4979.8 5378.5 5432.6 5626.9 4832.5 4967.9 5237.7 5867.9 5908.9 3680.5 3795.7 3800.9 3805.4 3805.8 4773.2 5407.5 5698.0 5755.5 5790.4 4593.1 4901.9 5452.8 5453.4 5479.2 4850.6 5137.2 5386.0 5456.0 5484.3 4651.7 4825.6 4867.4 5394.8 5428.3 5469.3 5479.3 5486.4 5531.3 5556.3 4562.5 4837.6 5360.2 5383.8 5428.0 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:17825.60 Median:62865.40 stddev:24311.75 (38.7%) stddev-sans-first:24345.99 08:29:46 INFO - PROCESS | 1944 | Values: 714.5 716.9 720.2 826.8 845.9 14554.3 15258.6 15265.5 15277.0 15283.0 13931.9 14008.6 14018.2 14050.6 14142.2 712.2 736.3 888.5 889.8 905.9 758.4 914.6 915.0 915.4 915.8 945.6 962.1 962.2 963.9 964.3 747.4 935.2 955.7 961.3 969.2 720.4 721.0 721.0 721.7 723.7 14390.5 14432.3 14446.6 14449.6 14518.5 13052.7 13158.3 13358.6 13537.4 13625.1 12043.3 12174.0 12343.8 12482.1 12793.2 4530.7 4542.2 4549.4 4733.4 4736.0 74873.6 75202.0 75448.9 76141.6 77034.6 10636.4 10722.4 10727.5 10737.0 10768.6 20303.8 20304.6 20311.6 20318.2 20331.8 7782.0 7851.4 7908.0 7955.9 8037.2 4414.8 4415.8 4426.9 4606.8 4609.4 3356.9 3454.7 3457.6 3457.6 3461.2 643.5 652.5 671.1 682.9 688.3 541.5 597.9 605.3 644.5 658.7 8354.8 8661.1 8868.6 8931.4 9172.9 622.5 634.2 649.9 657.7 668.7 4210.4 4220.9 4294.5 4359.5 4434.4 73505.9 83383.0 86834.6 89681.0 90092.0 74688.6 78470.6 79413.6 79585.2 80879.0 70077.3 70491.6 70501.0 70513.6 70715.6 8357.5 8367.7 8371.0 8579.6 8630.9 46287.0 46315.0 46549.5 46658.3 47299.1 59249.1 59480.1 60843.7 62589.4 63141.4 11137.2 11274.3 11374.8 11449.7 11868.9 9833.4 11783.6 11789.0 12284.1 12400.6 11387.4 11410.3 11599.2 11908.7 12011.8 9471.4 10413.1 10864.2 10866.2 10984.8 10175.0 10804.2 11292.0 11831.3 11907.1 8135.6 9706.4 9755.4 9783.7 9864.8 8594.5 8788.5 9567.4 9629.7 9883.0 08:29:46 INFO - PROCESS | 1944 | -------- Summary: end -------- 08:29:46 INFO - PROCESS | 1944 | 08:29:46 INFO - TEST-INFO | 1944: exit 0 08:29:46 INFO - TEST-OK | dromaeo_css | took 1116056ms 08:29:46 INFO - TEST-START | kraken 08:29:46 INFO - operating with platform_type : mac_ 08:29:46 INFO - Initialising browser for kraken test... 08:29:46 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpDzA5Hx/profile http://localhost:51907/getInfo.html) 08:29:48 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 08:29:48 INFO - PROCESS | 1948 | colorDepth:24 08:29:48 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 08:29:48 INFO - PROCESS | 1948 | __metrics 08:29:49 INFO - PROCESS | 1948 | 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" 08:29:49 ERROR - PROCESS | 1948 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:29:49 INFO - TEST-INFO | 1948: exit 0 08:29:49 INFO - Browser initialized. 08:29:49 INFO - Running cycle 1/1 for kraken test... 08:29:49 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpDzA5Hx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:01 INFO - PROCESS | 1949 | RSS: Main: 241627136 08:30:01 INFO - PROCESS | 1949 | 08:30:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51907/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:05 INFO - PROCESS | 1949 | RSS: Main: 291741696 08:30:05 INFO - PROCESS | 1949 | 08:30:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51907/tests/kraken/driver.html?testName=audio-dft) 08:30:08 INFO - PROCESS | 1949 | RSS: Main: 301371392 08:30:08 INFO - PROCESS | 1949 | 08:30:12 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51907/tests/kraken/driver.html?testName=audio-fft) 08:30:13 INFO - PROCESS | 1949 | RSS: Main: 393191424 08:30:13 INFO - PROCESS | 1949 | 08:30:15 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51907/tests/kraken/driver.html?testName=audio-oscillator) 08:30:15 INFO - PROCESS | 1949 | RSS: Main: 292282368 08:30:15 INFO - PROCESS | 1949 | 08:30:17 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51907/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:30:18 INFO - PROCESS | 1949 | RSS: Main: 297242624 08:30:18 INFO - PROCESS | 1949 | 08:30:21 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51907/tests/kraken/driver.html?testName=imaging-darkroom) 08:30:21 INFO - PROCESS | 1949 | RSS: Main: 333520896 08:30:21 INFO - PROCESS | 1949 | 08:30:24 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51907/tests/kraken/driver.html?testName=imaging-desaturate) 08:30:25 INFO - PROCESS | 1949 | RSS: Main: 334864384 08:30:25 INFO - PROCESS | 1949 | 08:30:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51907/tests/kraken/driver.html?testName=json-parse-financial) 08:30:28 INFO - PROCESS | 1949 | RSS: Main: 334557184 08:30:28 INFO - PROCESS | 1949 | 08:30:30 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51907/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:30 INFO - PROCESS | 1949 | RSS: Main: 311656448 08:30:30 INFO - PROCESS | 1949 | 08:30:32 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:33 INFO - PROCESS | 1949 | RSS: Main: 318316544 08:30:33 INFO - PROCESS | 1949 | 08:30:35 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:36 INFO - PROCESS | 1949 | RSS: Main: 321839104 08:30:36 INFO - PROCESS | 1949 | 08:30:39 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:39 INFO - PROCESS | 1949 | RSS: Main: 324468736 08:30:39 INFO - PROCESS | 1949 | 08:30:42 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:43 INFO - PROCESS | 1949 | RSS: Main: 325230592 08:30:43 INFO - PROCESS | 1949 | 08:30:44 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51907/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51907/tests/kraken/driver.html?testName=ai-astar) 08:30:45 INFO - PROCESS | 1949 | RSS: Main: 369045504 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | __start_tp_report 08:30:45 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 08:30:45 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 08:30:45 INFO - PROCESS | 1949 | |i|pagename|runs| 08:30:45 INFO - PROCESS | 1949 | |0;ai-astar;119;153;157;112;113;112;112;111;112;111 08:30:45 INFO - PROCESS | 1949 | |1;audio-beat-detection;163;123;118;167;118;118;137;113;150;118 08:30:45 INFO - PROCESS | 1949 | |2;audio-dft;184;187;186;185;186;182;184;184;183;184 08:30:45 INFO - PROCESS | 1949 | |3;audio-fft;79;76;57;107;56;57;60;62;126;55 08:30:45 INFO - PROCESS | 1949 | |4;audio-oscillator;84;87;93;70;74;69;95;70;68;99 08:30:45 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;117;130;86;83;87;84;89;123;87;71 08:30:45 INFO - PROCESS | 1949 | |6;imaging-darkroom;92;93;94;95;93;93;94;93;93;94 08:30:45 INFO - PROCESS | 1949 | |7;imaging-desaturate;84;85;86;84;84;84;84;86;87;87 08:30:45 INFO - PROCESS | 1949 | |8;json-parse-financial;55;55;54;55;55;54;55;57;54;54 08:30:45 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;67;78;76;75;72;71;70;71;71;71 08:30:45 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;87;87;86;85;86;85;84;91;123;88 08:30:45 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;142;143;148;151;136;139;140;150;174;140 08:30:45 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;167;166;162;184;223;165;166;165;170;166 08:30:45 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;67;66;67;66;67;66;66;65;66;65 08:30:45 INFO - PROCESS | 1949 | __end_tp_report 08:30:45 INFO - PROCESS | 1949 | __start_cc_report 08:30:45 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1979 08:30:45 INFO - PROCESS | 1949 | __end_cc_report 08:30:45 INFO - PROCESS | 1949 | __startTimestamp1464795045201__endTimestamp 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | ------- Summary: start ------- 08:30:45 INFO - PROCESS | 1949 | Number of tests: 14 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:121.20 Median:112.50 stddev:17.99 (16.0%) stddev-sans-first:19.06 08:30:45 INFO - PROCESS | 1949 | Values: 119.0 153.0 157.0 112.0 113.0 112.0 112.0 111.0 112.0 111.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:130.00 stddev:20.44 (15.7%) stddev-sans-first:18.46 08:30:45 INFO - PROCESS | 1949 | Values: 163.0 123.0 118.0 167.0 118.0 118.0 137.0 113.0 150.0 118.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:184.50 Median:184.50 stddev:1.51 (0.8%) stddev-sans-first:1.59 08:30:45 INFO - PROCESS | 1949 | Values: 184.0 187.0 186.0 185.0 186.0 182.0 184.0 184.0 183.0 184.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:73.50 Median:58.50 stddev:24.55 (42.0%) stddev-sans-first:25.95 08:30:45 INFO - PROCESS | 1949 | Values: 79.0 76.0 57.0 107.0 56.0 57.0 60.0 62.0 126.0 55.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:85.50 stddev:12.08 (14.1%) stddev-sans-first:12.76 08:30:45 INFO - PROCESS | 1949 | Values: 84.0 87.0 93.0 70.0 74.0 69.0 95.0 70.0 68.0 99.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:85.00 stddev:19.93 (23.4%) stddev-sans-first:19.59 08:30:45 INFO - PROCESS | 1949 | Values: 117.0 130.0 86.0 83.0 87.0 84.0 89.0 123.0 87.0 71.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.84 (0.9%) stddev-sans-first:0.73 08:30:45 INFO - PROCESS | 1949 | Values: 92.0 93.0 94.0 95.0 93.0 93.0 94.0 93.0 93.0 94.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.29 (1.5%) stddev-sans-first:1.30 08:30:45 INFO - PROCESS | 1949 | Values: 84.0 85.0 86.0 84.0 84.0 84.0 84.0 86.0 87.0 87.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.92 (1.7%) stddev-sans-first:0.97 08:30:45 INFO - PROCESS | 1949 | Values: 55.0 55.0 54.0 55.0 55.0 54.0 55.0 57.0 54.0 54.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:71.50 stddev:3.22 (4.5%) stddev-sans-first:2.82 08:30:45 INFO - PROCESS | 1949 | Values: 67.0 78.0 76.0 75.0 72.0 71.0 70.0 71.0 71.0 71.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:86.50 stddev:11.69 (13.5%) stddev-sans-first:12.34 08:30:45 INFO - PROCESS | 1949 | Values: 87.0 87.0 86.0 85.0 86.0 85.0 84.0 91.0 123.0 88.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:145.50 stddev:10.92 (7.5%) stddev-sans-first:11.48 08:30:45 INFO - PROCESS | 1949 | Values: 142.0 143.0 148.0 151.0 136.0 139.0 140.0 150.0 174.0 140.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:166.50 stddev:18.44 (11.1%) stddev-sans-first:19.41 08:30:45 INFO - PROCESS | 1949 | Values: 167.0 166.0 162.0 184.0 223.0 165.0 166.0 165.0 170.0 166.0 08:30:45 INFO - PROCESS | 1949 | 08:30:45 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 08:30:45 INFO - PROCESS | 1949 | Values: 67.0 66.0 67.0 66.0 67.0 66.0 66.0 65.0 66.0 65.0 08:30:45 INFO - PROCESS | 1949 | -------- Summary: end -------- 08:30:45 INFO - PROCESS | 1949 | 08:30:46 INFO - TEST-INFO | 1949: exit 0 08:30:46 INFO - TEST-OK | kraken | took 59175ms 08:30:46 INFO - SUITE-END | took 1175s 08:30:46 INFO - Completed test suite (00:19:35) 08:30:46 INFO - javascript benchmark 08:30:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3375.527426160338, 3411.2495251044775, 3420.7092397146243, 3440.8107554084513, 3486.686198242158, 5588.497035266825, 5623.943760562393, 5687.459691329327, 5687.8578035549135, 5715.79994700185, 4801.679538127017, 5559.19391688205, 5691.914621280674, 5761.049426844576, 5787.50806181474, 5055.5702765265, 5062.924056139161, 5096.821611243588, 5100.642954993134, 5225.372955245376, 5234.188700751371, 5269.499397557226, 5274.57803375731, 5280.0, 5312.707801070939, 5080.517350851663, 5087.135187018216, 5140.74296285188, 5146.639735218536, 5193.58451323895, 5066.468020857794, 5084.872878178038, 5103.209002604565, 5114.641975061747, 5166.4575219601775, 5061.924071138936, 5132.127538318495, 5155.7679904404395, 5156.74216289184, 5194.35070616173, 5524.99999999996, 5566.972165139189, 5579.302587176603, 5627.296587926509, 5646.971765141148, 5219.71291578963, 6163.3220345762375, 6168.383161683841, 6190.195274614316, 6198.008318669036, 5479.671219726829, 6144.692765361759, 6161.4146656067605, 6168.537359697995, 6175.0, 5630.774769009235, 5654.180143879114, 5719.170720245625, 5721.713914304268, 5791.420857914175, 6241.500679945593, 6246.500279977591, 6249.125122482856, 6258.030005349178, 6265.906011409833, 6077.799012581625, 6080.662873115721, 6084.202256465377, 6088.756449741961, 6128.2033335666965, 6026.879462410728, 6061.787837425669, 6128.233970753656, 6149.0, 6183.474404675653, 6120.908186377208, 6126.792505495304, 6130.111133885562, 6137.631742095488, 6151.861905547488, 6120.489360537716, 6171.444569988723, 6172.753089876354, 6173.56785025044, 6181.320054793969, 4854.927176092361, 4855.684380515255, 4925.630577706686, 4950.777215025332, 4964.478729733326, 4389.758563279051, 4466.0398014427055, 4486.102779444124, 4499.0776890737325, 4499.437570303712, 5567.0, 5635.830925072254, 5662.886742265132, 5703.515201207861, 5732.484076433141, 6153.476954458832, 6190.288116866528, 6218.875622487525, 6226.128342032119, 6242.375762423812, 5650.576206784425, 5662.037453632893, 5668.036433806263, 5669.489745922887, 5672.858178545487, 5242.7378631068295, 5291.920621190685, 5309.628326017141, 5343.064963631349, 5363.383210930793, 6244.313125556185, 6281.15204447412, 6295.307516173218, 6298.118263443122, 6298.559100862895, 6160.798644264505, 6178.165947597017, 6195.81412557624, 6213.751449941951, 6221.626702397871, 6137.683438940172, 6189.84525386869, 6192.752289908533, 6204.565680402328, 6254.468370188403, 6098.664573448503, 6104.877902441837, 6105.43490155904, 6130.969345153246, 6139.877202456015, 6120.723104933873, 6181.721822517908, 6189.659568723763, 6190.41191086846, 6197.070439434121, 5913.319968203712, 5971.880562388641, 5980.551458640706, 6011.368806275453, 6018.849528761641, 5943.108533719977, 6015.608985415934, 6020.819375418744, 6027.728752206249, 6047.0, 5686.203931449518, 5715.257016587817, 5832.9708351457975, 5863.530917526502, 5877.911831322619, 5989.730462129128, 6017.518598512021, 6018.976773948351, 6043.153958445733, 6054.788082417271, 5850.736716847837, 5851.941480585139, 5853.209816674695, 5857.502112320501, 5885.499732522783], "value": 5637.435769223071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.32961842276086, 489.88977480066984, 516.7132241605909, 523.7119584228675, 534.078714217974, 23914.641280380787, 42559.36160957584, 43225.78387108068, 43427.565724342785, 45330.54669453305, 44586.33120503195, 44942.55057449424, 45018.0, 45042.77478612611, 45213.32180017302, 9539.47532885691, 9761.316707830454, 9765.853512197322, 9770.462624555646, 9779.657711980082, 9577.952110239474, 9716.417014979124, 9727.27045471589, 9736.172425343855, 9757.658481953133, 9700.369475984075, 9714.708558743248, 9724.611015559369, 9730.075642813958, 9761.560729767141, 9662.08210220028, 9697.951510242403, 9723.46520941348, 9727.756806079833, 9728.854067188999, 9030.774230644221, 9173.990861005284, 9635.470049147294, 9747.317687761859, 9773.706788796348, 41161.0, 41546.58453415457, 41580.37629435561, 41598.96002599929, 42358.0, 3409.829508524564, 3632.691221246197, 3841.2701586698436, 3954.912399090273, 4502.684812063157, 31476.527852082236, 31835.04494865158, 32113.0, 32385.35229295401, 32415.837920810245, 3252.284497410566, 3277.7541684373527, 3369.2082360645227, 3397.167693914982, 3443.793372397639, 483505.0, 486898.0, 488003.0, 488005.0, 488359.0, 61874.76250475055, 62265.68867155635, 62590.0, 62631.06053409202, 62848.68575657184, 65558.67220663866, 65640.0, 65718.0, 65762.67118664471, 65986.67006664936, 16762.994220346573, 16971.896826706223, 16975.575610609587, 16985.23566439513, 17012.744808827876, 13050.608481745563, 13130.803037954438, 13154.0, 13168.0, 13186.142900711424, 15881.0, 16171.272292746855, 16179.0, 16204.189790510427, 16888.408905688488, 6554.934450655528, 6602.2407423146, 6645.84010196688, 6741.2921643226755, 6758.628275444898, 720.3161225412209, 821.3470291118456, 869.5000374784398, 875.9912400876037, 1025.4052649463292, 30546.0, 30563.0, 30914.0, 31284.217894552818, 31547.842260788548, 998.5620706183073, 1001.6694490818013, 1019.938803671782, 1089.7765957978595, 1176.4823477669797, 15859.682806343808, 16046.0, 16047.919760401357, 16627.757587270367, 16710.6657866842, 624840.0, 628199.0, 635277.0, 638413.0, 641731.0, 60664.39335606588, 60842.69578652167, 60912.69543652253, 61053.694731526055, 61092.0, 1266.170658218868, 1272.0904553244475, 1400.509821562445, 1422.8434872164053, 1446.3708286895235, 30894.53658195174, 31125.0, 31182.0, 31758.523622145225, 31877.840610796797, 50489.49510504995, 56941.43058569527, 58231.70884145552, 58404.4159558399, 60742.78514429775, 572639.0, 579823.0, 584408.0, 584767.0, 586237.0, 22997.770022299563, 23790.0, 26438.603420948315, 26502.0, 26924.730752692223, 45848.312275316566, 47854.760726196146, 48013.27980080232, 48623.0, 49598.50401496083, 40125.799371002955, 40389.1922161561, 40555.391669124394, 41549.58450415457, 41667.7916610415, 39397.80301098476, 40092.1981560373, 40421.0, 40495.79752101221, 40526.79736601298, 38746.80626596849, 38939.80530097331, 39192.020199495244, 39501.20997579975, 39684.0, 34696.306073878884, 34700.47949280712, 35586.46620300749, 35790.0, 36542.086447839014, 22013.779862201813, 25684.871575642002, 25726.742732573184, 26350.86824565865, 26428.471430571666], "value": 20278.830393365486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.1135100619582, 388.7647972976349, 389.67460212558694, 391.528208508747, 412.86788227767124, 13385.495262378601, 19498.317558885443, 19959.700604490943, 20588.794112058877, 20615.896920515377, 20121.29575464859, 20498.0, 21072.57854842902, 21418.57162856742, 21466.677999830015, 5739.856503587402, 6011.008183649693, 6013.1581578579035, 6019.879602407949, 6021.157038014681, 5714.657120572758, 5848.327442344121, 5892.880352732967, 5977.910331345033, 6676.332366763333, 6634.436072933807, 6652.06871038055, 6654.367835055662, 6656.13470248867, 6657.633830139341, 5632.5212356949705, 6337.112804207414, 6514.902276465857, 6524.021396790496, 6547.345265473462, 5386.946130538723, 5586.1062230043, 5606.971965140148, 5610.831675049755, 5616.1014237721765, 24578.508429831483, 24996.750032499807, 25025.749742502707, 25641.102561410262, 25856.095036673814, 2190.255313193506, 2300.378897697612, 2369.7037870266217, 2690.515707172708, 2708.146933715893, 15260.236988150658, 15297.388104475807, 15480.45818396362, 15547.689046219013, 15674.0, 1810.5021119192195, 1897.127321432143, 1907.7996810334853, 1918.808119188084, 1932.053293886, 108716.45641771819, 111649.44175279072, 112391.43804281008, 112477.43761281141, 114713.0, 8669.349798764992, 10383.376997380181, 10768.154330551284, 10805.891941080488, 10895.455227238606, 12049.216800907912, 12072.879271207352, 12724.809127863089, 13486.05597608158, 13509.189448633113, 7441.590712510756, 8545.529995850164, 8595.484270943763, 8694.391392602458, 8702.651893924172, 8673.826523469495, 8685.565721714016, 8705.738827835175, 8792.47245165292, 8829.293656507463, 6446.0, 6557.868842623217, 6566.080748695186, 6566.5403421760975, 6567.408933196035, 1510.3203558398764, 1822.8086050964723, 1826.643804458118, 1831.8259765322273, 1858.8048254933308, 900.6847603338779, 949.6230465824582, 955.0926619711302, 973.7176218896441, 974.512743628186, 16754.497365079067, 18580.907095464707, 20862.165513379598, 21224.0, 21586.136554537643, 929.9488528131018, 977.7462947905005, 979.2019504104132, 993.3245393132595, 1073.8228192348174, 11249.04383127427, 11340.262882912584, 11468.426578671033, 11691.590794322328, 11720.706982325339, 545740.2712986489, 548227.0, 557884.0, 558088.0, 569896.0, 110021.44989275002, 113407.0, 113767.0, 114532.42733786278, 115544.0, 991.5736233419699, 1021.8262895307666, 1028.28534168752, 1033.9689809305733, 1037.9740506487246, 19031.52421189426, 19272.0, 19272.421827345202, 19299.324523641604, 19328.516787080436, 46741.76629116833, 46803.765981169876, 47394.05211895812, 48666.75666621644, 49007.754961224964, 69744.30255697378, 70423.64788176026, 70619.0, 71025.28974710187, 71629.0, 7795.765877957659, 7844.450888437983, 7883.7240696575345, 7899.763007109796, 8069.991251093613, 12453.75092498163, 12568.560100396442, 12608.117431779687, 12611.117221794386, 12677.429515671633, 10650.45641248318, 10816.66948402575, 10822.675319740105, 10835.566577336818, 10860.346331132429, 10010.0, 10408.375497470177, 10438.269321147447, 10457.215708822021, 10939.671809845717, 10167.7458063549, 10899.346039237671, 11020.669379918616, 11082.501287441923, 11126.276792008817, 7754.728584499516, 7848.568328741974, 7909.327707144727, 7950.165232650488, 8059.556724380215, 10517.894821051692, 10777.407242601732, 10796.244262901522, 10914.0, 10927.07119894786], "value": 9648.4521768757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.7266633006554, 1490.5528341497547, 1498.4006397441021, 1779.937702180424, 1963.9312624058161, 7794.220577942203, 8544.743657690271, 8731.17053879882, 8750.649974001033, 8879.422837515556, 8457.746267611967, 8701.86947195791, 8704.73885783426, 8704.73885783426, 8707.521086340248, 2080.843936704746, 2156.773538778429, 2158.6006588781124, 2316.3977365884907, 2359.634256690216, 2104.968425473619, 2123.182574708733, 2316.0967222783042, 2390.3187591536416, 2391.7727815857465, 1982.7224188613607, 2356.1046802215133, 2364.5507353602848, 2368.7749663782, 2369.490559529701, 2095.298075144828, 2161.4056134562966, 2255.8533695309834, 2299.9310020699404, 2306.9468173325754, 2092.7384076990375, 2297.5864344417996, 2358.1392791630933, 2362.2795047510504, 2377.833551651368, 8717.171868672465, 8788.20906118446, 8825.955870220672, 8831.77920551985, 8842.955785221096, 5159.509846564571, 5325.0, 5329.920051199235, 5330.147176451788, 5333.066713325192, 3515.82420878955, 3637.4907512948207, 3709.443583462475, 3713.3130370881204, 3714.8699795507296, 1068.4978060311641, 1096.802575536403, 1106.3859557945336, 1120.9047230985377, 1121.6410748560463, 11568.768624627544, 11587.246828956175, 11592.507610863704, 11598.652040438801, 11609.941950290278, 4559.794809233593, 4719.174144524694, 4750.192467280502, 4789.425268967746, 4790.77835152034, 9842.901570984342, 9919.256055795844, 9919.80160396788, 9920.553575089138, 9923.0, 5274.604404669665, 5277.340332458442, 5285.497877701612, 5286.048511268009, 5296.576273898079, 4455.68810183284, 4704.976475117671, 4790.137775200497, 4856.635752318591, 4860.80556777732, 3586.7130629549574, 3683.963160368362, 3684.0605645560745, 3688.6864616507896, 3697.6302369762807, 1158.808796548577, 1166.1720178673067, 1179.7522520270838, 1181.7341098252825, 1185.709501172201, 809.9919000809916, 843.5276245302528, 894.789724414762, 894.9642014319485, 967.3734915826672, 5820.679862607597, 5826.854328641818, 6052.939470605325, 6071.2714474263375, 6138.171346868205, 917.1974522292994, 964.7298756348234, 965.0783501755807, 972.5672075926145, 1013.2451323763843, 3988.501437320335, 4015.0, 4047.6964227683043, 4065.4714887064492, 4123.113530590937, 10530.262881598215, 10816.296940698829, 10892.40091794959, 10904.563817447372, 10904.672859814218, 10750.462476876124, 10848.67453976382, 10853.891461085446, 10856.294340867818, 10859.13126949842, 7779.222077792176, 8089.231523005187, 8136.918630813734, 8182.918170818335, 8284.8343033139, 6080.331163571915, 6089.5737298388685, 6159.476444502267, 6176.969115154396, 6211.440970312694, 9894.356866803635, 10123.0, 10184.439855808001, 10208.693739187836, 10209.489525523546, 7895.289423951872, 9928.751781205528, 10419.895801041892, 10440.68677939663, 10444.791104178026, 7484.70061197546, 7666.501677390952, 7742.303192712683, 7746.806329841799, 7754.534727916343, 8266.834663306821, 8274.669013239403, 8289.088200297841, 8301.335893128657, 8400.075991640793, 7561.09266887977, 7582.203868593719, 7602.353799927068, 7669.654865531176, 7698.769036928901, 7372.262773722585, 7379.594122323324, 7387.519811212254, 7514.0, 7538.057742782153, 6893.310668933267, 6985.615791131537, 7059.788206353817, 7096.929030709834, 7108.857822843617, 5005.149124648746, 5009.248612707977, 5020.748962551785, 5149.0474262261605, 5155.484451554815, 4474.821007159677, 4914.557689807934, 4977.377827771528, 5069.619778516552, 5095.082885080721], "value": 4573.518771068734, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.23865053318303, 310.6986223363337, 316.20159098276844, 317.61251273446385, 321.2482790270766, 4522.706024108431, 4855.732934688591, 4970.155073637481, 5194.5324920757075, 5318.76065577049, 4120.299549076657, 5052.772625231864, 5176.197689358156, 5182.248573956774, 5232.973835130829, 3270.3622793555264, 3685.4471829225695, 3826.9043273918237, 3864.845406183749, 3889.183271512986, 3601.6769183876386, 3745.9250814983684, 3792.6207379262128, 3894.240623078501, 3920.882373528799, 3358.9832050839714, 3484.9303013939834, 3577.3365385095926, 3774.811259437031, 3954.7231693781446, 3445.4659527773233, 3903.882883513485, 3918.1967696622123, 3944.447777311179, 3958.5447673517624, 3261.249912520134, 3381.425157723193, 3389.1357703785625, 3428.8457019434186, 3437.295354452331, 4318.775830447322, 4667.719936803803, 4925.876853078666, 5017.7491125443585, 5039.21892106724, 3902.5902280260448, 4067.8983025424304, 4528.0, 4535.455745310584, 4561.703489273186, 5091.1854103343285, 5284.180951952453, 5301.019311427398, 5516.800255851596, 5529.157235721175, 3252.650150187693, 3822.598627144165, 3826.1773718650347, 3829.272438236754, 3843.615638436134, 4754.857354279377, 5483.7516618186355, 5636.267285252891, 5707.200991861143, 5709.743061562198, 3336.966630333714, 3724.845297957642, 3749.306378319965, 3854.5181852268465, 3856.36369998947, 3080.1683545442606, 3500.949715085464, 3923.5085720567645, 3948.506436695413, 3976.1053762903116, 4043.332850079763, 4240.38514415408, 4383.364412160219, 4507.706999045117, 4555.772211389417, 4400.405945197422, 5524.281843360418, 5531.0, 5534.418886016911, 5550.72246387679, 1099.444780385916, 1216.3370962825281, 1336.8930485561132, 1344.126317893377, 1345.7779466387935, 725.7242247945746, 798.9320907722908, 826.5454000299811, 829.5022986208347, 851.5997481183833, 3719.5350581177354, 3971.4241434991764, 3973.523177218752, 4111.444954931106, 4360.345948107809, 4289.549597292302, 4569.514907655025, 4735.95264047362, 4842.225243960949, 4860.805567777249, 3861.5559210690567, 3865.7100717446306, 4200.768957707355, 4310.331898555729, 4360.585744354281, 2468.987655061749, 2627.316897606636, 2748.175547335831, 2760.30992251937, 2895.4643390972738, 5455.263144745862, 5592.692401917852, 5889.558283128783, 5914.645121292736, 5919.289685237799, 5624.325080990226, 5806.477417032487, 5816.970915145397, 5857.824265272048, 5858.707064646751, 5459.372172200168, 5860.296764388216, 5871.73577189036, 5925.34821169671, 5946.316173640001, 5616.606837521498, 5639.746211420414, 5668.372241765356, 5727.427257274239, 5743.0, 4655.604273636779, 4979.825706100414, 5378.542823860016, 5432.59255555827, 5626.943730562805, 4832.468428472936, 4967.9006419870675, 5237.685738855681, 5867.911981320198, 5908.852278693067, 3680.4663323817363, 3795.6963442925053, 3800.942985855159, 3805.3911374179997, 3805.7631269837966, 4773.236282194832, 5407.458874220828, 5698.0, 5755.510781583444, 5790.420957904176, 4593.127305811885, 4901.852944411673, 5452.781888724407, 5453.427390124139, 5479.205515200194, 4850.563449289581, 5137.17805151174, 5385.973070134624, 5455.972720136374, 5484.341878974389, 4651.7441540714235, 4825.638077144158, 4867.3915760529935, 5394.811181608782, 5428.321459817523, 5469.343678758574, 5479.342478902558, 5486.369067557122, 5531.262025297504, 5556.333240011063, 4562.498125206291, 4837.572915989721, 5360.2227676987395, 5383.81156659515, 5427.972860135674], "value": 3900.699870234427, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.5177005521274, 716.8673795347861, 720.1682057223908, 826.7726375246806, 845.9365547583933, 14554.272286385678, 15258.618534536643, 15265.542033738991, 15277.006994545362, 15283.0, 13931.860681393184, 14008.64978375541, 14018.228997405138, 14050.578482645511, 14142.222177780219, 712.2343480758179, 736.3483317264233, 888.5424006636588, 889.8353804546148, 905.8550631898897, 758.4463341760518, 914.6204325205032, 915.0209276074603, 915.4232833315002, 915.8168366326728, 945.5792172483248, 962.1244667352711, 962.2398305338809, 963.9228861691083, 964.3297907953975, 747.4431548496351, 935.1536859142487, 955.7371722776222, 961.27423795035, 969.1713584884892, 720.4416577152696, 720.9963950180268, 721.0410154494509, 721.7257442171993, 723.6671131279618, 14390.496332628307, 14432.278386080654, 14446.566603001927, 14449.566513004627, 14518.491852785102, 13052.673683157902, 13158.342082895913, 13358.599242022754, 13537.390817413141, 13625.114367566175, 12043.277403355827, 12174.0, 12343.75312493754, 12482.126251162423, 12793.232406055573, 4530.6602004849765, 4542.2051141050515, 4549.431321084864, 4733.3846599942135, 4735.976320118411, 74873.62563187149, 75202.0, 75448.86826697605, 76141.6192919043, 77034.61482692607, 10636.41499717523, 10722.356658600509, 10727.463626818628, 10737.0, 10768.623098191525, 20303.796962030487, 20304.59390812205, 20311.593768124556, 20318.187272508934, 20331.796682033288, 7782.0, 7851.371890248881, 7907.9604601976625, 7955.920440795634, 8037.156098609678, 4414.823407063682, 4415.757133357696, 4426.867193984185, 4606.769661516911, 4609.377734005866, 3356.9496457553155, 3454.6545345465756, 3457.6369481204615, 3457.6369481204615, 3461.169319363385, 643.5334382572692, 652.5073569455031, 671.1141293492543, 682.8839097353462, 688.277308825731, 541.5261646059698, 597.8654802669365, 605.2918085839578, 644.5025526086415, 658.6673729766437, 8354.791130221793, 8661.13388661129, 8868.600912958933, 8931.374803763803, 9172.8624070639, 622.4615707413145, 634.2230767310008, 649.9360962222453, 657.7278041742294, 668.7425341243656, 4210.431591735138, 4220.894477638084, 4294.549072347359, 4359.455068116486, 4434.357018232403, 73505.89741153887, 83383.0, 86834.56582717173, 89681.0, 90092.0, 74688.62655686795, 78470.6076469614, 79413.60293198613, 79585.20414795777, 80879.0, 70077.29922700708, 70491.64754176197, 70501.0, 70513.64743176251, 70715.64642176757, 8357.540335281376, 8367.748967530984, 8370.958145209235, 8579.598673809734, 8630.870536941826, 46287.0, 46315.0, 46549.53450465587, 46658.300125497466, 47299.05401892012, 59249.11126333195, 59480.10779838393, 60843.69578152081, 62589.37410625836, 63141.421464463754, 11137.164712646745, 11274.323540587591, 11374.829377559177, 11449.656510304705, 11868.940655296668, 9833.4099954003, 11783.587574434854, 11789.0, 12284.0786940978, 12400.56598019065, 11387.43062846871, 11410.315381077162, 11599.188056835941, 11908.702282443008, 12011.81982270284, 9471.431714097176, 10413.077486683096, 10864.185186110915, 10866.239363244496, 10984.771270751105, 10175.035185442124, 10804.24370294072, 11292.040176585082, 11831.349275789915, 11907.106966977684, 8135.552544610104, 9706.417614943126, 9755.390505513005, 9783.706488805346, 9864.802703946025, 8594.484330939913, 8788.45819572402, 9567.37812042215, 9629.711108666752, 9882.950585247028], "value": 7026.010166371505, "unit": "score"}], "name": "dromaeo_css", "value": 7190.992815120691, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 153.0, 157.0, 112.0, 113.0, 112.0, 112.0, 111.0, 112.0, 111.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 123.0, 118.0, 167.0, 118.0, 118.0, 137.0, 113.0, 150.0, 118.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 186.0, 185.0, 186.0, 182.0, 184.0, 184.0, 183.0, 184.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 57.0, 107.0, 56.0, 57.0, 60.0, 62.0, 126.0, 55.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 93.0, 70.0, 74.0, 69.0, 95.0, 70.0, 68.0, 99.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 86.0, 83.0, 87.0, 84.0, 89.0, 123.0, 87.0, 71.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 95.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 84.0, 84.0, 84.0, 84.0, 86.0, 87.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 57.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 76.0, 75.0, 72.0, 71.0, 70.0, 71.0, 71.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 85.0, 86.0, 85.0, 84.0, 91.0, 123.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 143.0, 148.0, 151.0, 136.0, 139.0, 140.0, 150.0, 174.0, 140.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 162.0, 184.0, 223.0, 165.0, 166.0, 165.0, 170.0, 166.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1469.8, "alertThreshold": 2.0}]} 08:30:46 INFO - Return code: 0 08:30:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:30:46 ERROR - # TBPL SUCCESS # 08:30:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:46 INFO - 2016-06-01 08:30:46.500 screenresolution[1954:17106] starting screenresolution argv=screenresolution get 08:30:46 INFO - 2016-06-01 08:30:46.503 screenresolution[1954:17106] Display 0: 1600x1200x32@60 08:30:46 INFO - 2016-06-01 08:30:46.509 screenresolution[1955:17109] starting screenresolution argv=screenresolution list 08:30:46 INFO - Available Modes on Display 0 08:30:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:46 INFO - Intel Iris: 08:30:46 INFO - Chipset Model: Intel Iris 08:30:46 INFO - Type: GPU 08:30:46 INFO - Bus: Built-In 08:30:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:46 INFO - Vendor: Intel (0x8086) 08:30:46 INFO - Device ID: 0x0a2e 08:30:46 INFO - Revision ID: 0x0009 08:30:46 INFO - Displays: 08:30:46 INFO - Display: 08:30:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:46 INFO - Display Serial Number: 1600x1200 60 08:30:46 INFO - Main Display: Yes 08:30:46 INFO - Mirror: Off 08:30:46 INFO - Online: Yes 08:30:46 INFO - Rotation: Supported 08:30:46 INFO - Return code: 0 08:30:46 INFO - Running post-action listener: _resource_record_post_action 08:30:46 INFO - ##### 08:30:46 INFO - ##### Finished run-tests step (success) 08:30:46 INFO - ##### 08:30:46 INFO - Running post-run listener: _resource_record_post_run 08:30:46 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 839680; Write bytes: 697546752; Read time: 9; Write time: 1914 08:30:46 INFO - TinderboxPrint: CPU usage
54.1% 08:30:46 INFO - TinderboxPrint: I/O read bytes / time
839,680 / 9 08:30:46 INFO - TinderboxPrint: I/O write bytes / time
697,546,752 / 1,914 08:30:46 INFO - TinderboxPrint: CPU idle
2,225.0 (45.9%) 08:30:46 INFO - TinderboxPrint: CPU system
973.3 (20.1%) 08:30:46 INFO - TinderboxPrint: CPU user
1,647.4 (34.0%) 08:30:46 INFO - TinderboxPrint: Swap in / out
399,519,744 / 0 08:30:46 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 397117952; Write bytes: 391218688; Read time: 30056; Write time: 700 08:30:46 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 839680; Write bytes: 294106624; Read time: 9; Write time: 1165 08:30:47 INFO - Running post-run listener: _upload_blobber_files 08:30:47 INFO - Blob upload gear active. 08:30:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:47 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 08:30:47 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'] 08:30:47 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 08:30:47 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:30:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:30:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:48 INFO - (blobuploader) - INFO - Done attempting. 08:30:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:30:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:30:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:49 INFO - (blobuploader) - INFO - Done attempting. 08:30:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:30:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:30:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:52 INFO - (blobuploader) - INFO - Done attempting. 08:30:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:52 INFO - Return code: 0 08:30:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8a8b783ed8097b05ea6c8ede7fc866a985ad88f33b75038b7f42819cf3bbbbb5b3570a8beaca0f1a84fb1e6297d0b15cf62ffdaf2cc71c1d5c3953c30042a51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f640c92d6f4ac9c8b1a5d55da9e9b4bac989468e30d90003e54491a60d969828236f9b72a6fa25e0efb26e0191e0206247315277311f497d76a9da15c4e228d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8b9008cff70dd75d4bc5d4a238e733d73393051ac854b798c5212a952fa2e829e6c0f379e1553f4c443092b8079ca6e96c639f222e0ab5adb15989e9f0e54a"} 08:30:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:52 INFO - Contents: 08:30:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8a8b783ed8097b05ea6c8ede7fc866a985ad88f33b75038b7f42819cf3bbbbb5b3570a8beaca0f1a84fb1e6297d0b15cf62ffdaf2cc71c1d5c3953c30042a51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f640c92d6f4ac9c8b1a5d55da9e9b4bac989468e30d90003e54491a60d969828236f9b72a6fa25e0efb26e0191e0206247315277311f497d76a9da15c4e228d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8b9008cff70dd75d4bc5d4a238e733d73393051ac854b798c5212a952fa2e829e6c0f379e1553f4c443092b8079ca6e96c639f222e0ab5adb15989e9f0e54a"} 08:30:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:52 INFO - Copying logs to upload dir... 08:30:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.616088 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-06-01 08:30:52.310744) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 08:30:52.313992) ========= 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.EzAZoKS8YY/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.OdjKtstbwW/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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/f8a8b783ed8097b05ea6c8ede7fc866a985ad88f33b75038b7f42819cf3bbbbb5b3570a8beaca0f1a84fb1e6297d0b15cf62ffdaf2cc71c1d5c3953c30042a51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f640c92d6f4ac9c8b1a5d55da9e9b4bac989468e30d90003e54491a60d969828236f9b72a6fa25e0efb26e0191e0206247315277311f497d76a9da15c4e228d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8b9008cff70dd75d4bc5d4a238e733d73393051ac854b798c5212a952fa2e829e6c0f379e1553f4c443092b8079ca6e96c639f222e0ab5adb15989e9f0e54a"} build_url:https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010967 build_url: 'https://queue.taskcluster.net/v1/task/aevCuLtsRwyQtEw0kbGVKg/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/f8a8b783ed8097b05ea6c8ede7fc866a985ad88f33b75038b7f42819cf3bbbbb5b3570a8beaca0f1a84fb1e6297d0b15cf62ffdaf2cc71c1d5c3953c30042a51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f640c92d6f4ac9c8b1a5d55da9e9b4bac989468e30d90003e54491a60d969828236f9b72a6fa25e0efb26e0191e0206247315277311f497d76a9da15c4e228d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d8b9008cff70dd75d4bc5d4a238e733d73393051ac854b798c5212a952fa2e829e6c0f379e1553f4c443092b8079ca6e96c639f222e0ab5adb15989e9f0e54a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 08:30:52.345682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:30:52.346081) ========= 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.EzAZoKS8YY/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.OdjKtstbwW/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005013 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 08:30:52.394571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 08:30:52.394887) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 08:30:52.395231) ========= ========= Total master_lag: 0.18 =========