builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0185 starttime: 1464879433.36 results: success (0) buildid: 20160602060755 builduid: 829715e25321462a9f60e88bee88900e revision: 92e0c73391e71a400e2c6674bca5ca70804ab081 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.360825) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.361286) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.361561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.382920) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.383302) ========= 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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-02 07:57:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-06-02 07:57:13 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108441 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.511566) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.511874) ========= 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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.020842 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.560366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:13.560716) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 92e0c73391e71a400e2c6674bca5ca70804ab081 --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 92e0c73391e71a400e2c6674bca5ca70804ab081 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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-02 07:57:13,634 truncating revision to first 12 chars 2016-06-02 07:57:13,634 Setting DEBUG logging. 2016-06-02 07:57:13,634 attempt 1/10 2016-06-02 07:57:13,634 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/92e0c73391e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 07:57:14,201 unpacking tar archive at: mozilla-central-92e0c73391e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.835315 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:14.416119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:14.416436) ========= script_repo_revision: 92e0c73391e71a400e2c6674bca5ca70804ab081 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:14.416849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:14.417138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 07:57:14.434295) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-06-02 07:57:14.434659) ========= /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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:14 INFO - MultiFileLogger online at 20160602 07:57:14 in /builds/slave/test 07:57:14 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 07:57:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:57:14 INFO - {'append_to_log': False, 07:57:14 INFO - 'base_work_dir': '/builds/slave/test', 07:57:14 INFO - 'blob_upload_branch': 'Firefox', 07:57:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:57:14 INFO - 'branch': 'Firefox', 07:57:14 INFO - 'buildbot_json_path': 'buildprops.json', 07:57:14 INFO - 'config_files': ('talos/mac_config.py',), 07:57:14 INFO - 'default_actions': ('clobber', 07:57:14 INFO - 'read-buildbot-config', 07:57:14 INFO - 'download-and-extract', 07:57:14 INFO - 'populate-webroot', 07:57:14 INFO - 'create-virtualenv', 07:57:14 INFO - 'install', 07:57:14 INFO - 'run-tests'), 07:57:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:57:14 INFO - 'download_minidump_stackwalk': True, 07:57:14 INFO - 'download_symbols': 'ondemand', 07:57:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:57:14 INFO - 'tooltool.py': '/tools/tooltool.py', 07:57:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:57:14 INFO - '/tools/misc-python/virtualenv.py')}, 07:57:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:57:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:57:14 INFO - 'installer_path': 'installer.exe', 07:57:14 INFO - 'log_level': 'info', 07:57:14 INFO - 'log_name': 'talos', 07:57:14 INFO - 'log_to_console': True, 07:57:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:57:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:57:14 INFO - 'opt_config_files': (), 07:57:14 INFO - 'pip_index': False, 07:57:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:57:14 INFO - 'cmd': ('bash', 07:57:14 INFO - '-c', 07:57:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:57:14 INFO - 'enabled': True, 07:57:14 INFO - 'halt_on_failure': False, 07:57:14 INFO - 'name': 'check_screen_resolution'},), 07:57:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:57:14 INFO - 'cmd': ('bash', 07:57:14 INFO - '-c', 07:57:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:57:14 INFO - 'enabled': True, 07:57:14 INFO - 'halt_on_failure': False, 07:57:14 INFO - 'name': 'check_screen_resolution'},), 07:57:14 INFO - 'run_cmd_checks_enabled': True, 07:57:14 INFO - 'sps_profile': False, 07:57:14 INFO - 'sps_profile_interval': 0, 07:57:14 INFO - 'suite': 'dromaeojs', 07:57:14 INFO - 'system_bits': '32', 07:57:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:57:14 INFO - 'title': 't-yosemite-r7-0185', 07:57:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:57:14 INFO - 'use_talos_json': True, 07:57:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:57:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:57:14 INFO - 'work_dir': 'build'} 07:57:14 INFO - ##### 07:57:14 INFO - ##### Running clobber step. 07:57:14 INFO - ##### 07:57:14 INFO - Running pre-action listener: _resource_record_pre_action 07:57:14 INFO - Running main action method: clobber 07:57:14 INFO - rmtree: /builds/slave/test/build 07:57:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:57:16 INFO - Running post-action listener: _resource_record_post_action 07:57:16 INFO - ##### 07:57:16 INFO - ##### Finished clobber step (success) 07:57:16 INFO - ##### 07:57:16 INFO - ##### 07:57:16 INFO - ##### Running read-buildbot-config step. 07:57:16 INFO - ##### 07:57:16 INFO - Running pre-action listener: _resource_record_pre_action 07:57:16 INFO - Running main action method: read_buildbot_config 07:57:16 INFO - Using buildbot properties: 07:57:16 INFO - { 07:57:16 INFO - "project": "", 07:57:16 INFO - "product": "firefox", 07:57:16 INFO - "script_repo_revision": "production", 07:57:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:57:16 INFO - "repository": "", 07:57:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:57:16 INFO - "buildid": "20160602060755", 07:57:16 INFO - "pgo_build": "False", 07:57:16 INFO - "basedir": "/builds/slave/test", 07:57:16 INFO - "buildnumber": 18, 07:57:16 INFO - "slavename": "t-yosemite-r7-0185", 07:57:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:57:16 INFO - "platform": "yosemite_r7", 07:57:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:57:16 INFO - "branch": "mozilla-central", 07:57:16 INFO - "repo_path": "mozilla-central", 07:57:16 INFO - "revision": "92e0c73391e71a400e2c6674bca5ca70804ab081", 07:57:16 INFO - "stage_platform": "macosx64", 07:57:16 INFO - "builduid": "829715e25321462a9f60e88bee88900e", 07:57:16 INFO - "slavebuilddir": "test" 07:57:16 INFO - } 07:57:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:57:16 INFO - Running post-action listener: _resource_record_post_action 07:57:16 INFO - ##### 07:57:16 INFO - ##### Finished read-buildbot-config step (success) 07:57:16 INFO - ##### 07:57:16 INFO - ##### 07:57:16 INFO - ##### Running download-and-extract step. 07:57:16 INFO - ##### 07:57:16 INFO - Running pre-action listener: _resource_record_pre_action 07:57:16 INFO - Running main action method: download_and_extract 07:57:16 INFO - mkdir: /builds/slave/test/build/tests 07:57:16 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')]} 07:57:16 INFO - https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:57:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:57:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:57:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:17 INFO - Downloaded 1199 bytes. 07:57:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:57:17 INFO - Using the following test package requirements: 07:57:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:57:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:57:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:57:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'jsshell-mac.zip'], 07:57:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:57:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:57:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:57:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:57:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:57:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:57:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:57:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:57: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')]} 07:57:17 INFO - https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:57:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:57:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:57:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:19 INFO - Downloaded 18697057 bytes. 07:57:19 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'] 07:57:19 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 07:57:22 INFO - Return code: 0 07:57:22 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 07:57:22 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')]} 07:57:22 INFO - https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:57:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:57:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:57:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:23 INFO - Downloaded 18697057 bytes. 07:57:23 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'] 07:57:23 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 07:57:27 INFO - Return code: 0 07:57: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')]} 07:57:27 INFO - https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:57:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:57:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:57:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:29 INFO - Downloaded 10834055 bytes. 07:57:29 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'] 07:57:29 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 07:57:29 INFO - Return code: 0 07:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:29 INFO - https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:57:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:57:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bTCma_BNTaeXGBxAGvGMqA/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 07:57:34 INFO - Downloaded 111078290 bytes. 07:57:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:34 INFO - mkdir: /builds/slave/test/properties 07:57:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:57:34 INFO - Writing to file /builds/slave/test/properties/build_url 07:57:34 INFO - Contents: 07:57:34 INFO - build_url:https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:57:35 INFO - Running post-action listener: _resource_record_post_action 07:57:35 INFO - Running post-action listener: set_extra_try_arguments 07:57:35 INFO - ##### 07:57:35 INFO - ##### Finished download-and-extract step (success) 07:57:35 INFO - ##### 07:57:35 INFO - ##### 07:57:35 INFO - ##### Running populate-webroot step. 07:57:35 INFO - ##### 07:57:35 INFO - Running pre-action listener: _resource_record_pre_action 07:57:35 INFO - Running main action method: populate_webroot 07:57:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:57:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:57:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:57:35 INFO - '--tppagecycles', 07:57:35 INFO - '7'], 07:57:35 INFO - 'tests': ['tsvgm']}}, 07:57:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:57:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:57:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:57:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:57:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:57:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['damp', 'tps']}, 07:57:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'tests': ['damp', 'tps']}, 07:57:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['dromaeo_dom']}, 07:57:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:57:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['a11yr', 07:57:35 INFO - 'ts_paint', 07:57:35 INFO - 'tpaint', 07:57:35 INFO - 'sessionrestore', 07:57:35 INFO - 'sessionrestore_no_auto_restore', 07:57:35 INFO - 'tabpaint']}, 07:57:35 INFO - 'other-e10s': {'tests': ['a11yr', 07:57:35 INFO - 'ts_paint', 07:57:35 INFO - 'tpaint', 07:57:35 INFO - 'sessionrestore', 07:57:35 INFO - 'sessionrestore_no_auto_restore', 07:57:35 INFO - 'tabpaint']}, 07:57:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:57:35 INFO - 'ts_paint', 07:57:35 INFO - 'tpaint', 07:57:35 INFO - 'sessionrestore', 07:57:35 INFO - 'sessionrestore_no_auto_restore', 07:57:35 INFO - 'tabpaint']}, 07:57:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:57:35 INFO - 'ts_paint', 07:57:35 INFO - 'tpaint', 07:57:35 INFO - 'sessionrestore', 07:57:35 INFO - 'sessionrestore_no_auto_restore', 07:57:35 INFO - 'tabpaint']}, 07:57:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['a11yr', 07:57:35 INFO - 'ts_paint', 07:57:35 INFO - 'tpaint', 07:57:35 INFO - 'sessionrestore', 07:57:35 INFO - 'sessionrestore_no_auto_restore', 07:57:35 INFO - 'tabpaint']}, 07:57:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['a11yr', 07:57:35 INFO - 'ts_paint', 07:57:35 INFO - 'tpaint', 07:57:35 INFO - 'sessionrestore', 07:57:35 INFO - 'sessionrestore_no_auto_restore', 07:57:35 INFO - 'tabpaint']}, 07:57:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['tsvgx', 07:57:35 INFO - 'tsvgr_opacity', 07:57:35 INFO - 'tart', 07:57:35 INFO - 'tscrollx', 07:57:35 INFO - 'cart']}, 07:57:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:57:35 INFO - 'tsvgr_opacity', 07:57:35 INFO - 'tart', 07:57:35 INFO - 'tscrollx', 07:57:35 INFO - 'cart']}, 07:57:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'talos_options': ['--disable-e10s'], 07:57:35 INFO - 'tests': ['tp5o']}, 07:57:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'tests': ['tp5o']}, 07:57:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'talos_options': ['--disable-e10s', 07:57:35 INFO - '--xperf_path', 07:57:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:35 INFO - 'tests': ['tp5n']}, 07:57:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:57:35 INFO - 'talos_options': ['--xperf_path', 07:57:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:57:35 INFO - 'tests': ['tp5n']}}, 07:57:35 INFO - 'talos.zip': {'path': '', 07:57:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:57:35 INFO - Running post-action listener: _resource_record_post_action 07:57:35 INFO - ##### 07:57:35 INFO - ##### Finished populate-webroot step (success) 07:57:35 INFO - ##### 07:57:35 INFO - ##### 07:57:35 INFO - ##### Running create-virtualenv step. 07:57:35 INFO - ##### 07:57:35 INFO - Running pre-action listener: _resource_record_pre_action 07:57:35 INFO - Running main action method: create_virtualenv 07:57:35 INFO - Creating virtualenv /builds/slave/test/build/venv 07:57:35 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 07:57:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:57:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:57:36 INFO - Using real prefix '/tools/python27' 07:57:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:57:36 INFO - Installing distribute.............................................................................................................................................................................................done. 07:57:39 INFO - Installing pip.................done. 07:57:39 INFO - Return code: 0 07:57:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:39 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 07:57:39 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 07:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:39 INFO - 'HOME': '/Users/cltbld', 07:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:39 INFO - 'LOGNAME': 'cltbld', 07:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:39 INFO - 'MOZ_NO_REMOTE': '1', 07:57:39 INFO - 'NO_EM_RESTART': '1', 07:57:39 INFO - 'PAGER': '/bin/cat', 07:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:39 INFO - 'PWD': '/builds/slave/test', 07:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:39 INFO - 'SHELL': '/bin/bash', 07:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:57:39 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:39 INFO - 'USER': 'cltbld', 07:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:39 INFO - 'XPC_FLAGS': '0x0', 07:57:39 INFO - 'XPC_SERVICE_NAME': '0', 07:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:39 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 07:57:39 INFO - Cleaning up... 07:57:39 INFO - Return code: 0 07:57:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:39 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:39 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 07:57:39 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 07:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:39 INFO - 'HOME': '/Users/cltbld', 07:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:39 INFO - 'LOGNAME': 'cltbld', 07:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:39 INFO - 'MOZ_NO_REMOTE': '1', 07:57:39 INFO - 'NO_EM_RESTART': '1', 07:57:39 INFO - 'PAGER': '/bin/cat', 07:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:39 INFO - 'PWD': '/builds/slave/test', 07:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:39 INFO - 'SHELL': '/bin/bash', 07:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:57:39 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:39 INFO - 'USER': 'cltbld', 07:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:39 INFO - 'XPC_FLAGS': '0x0', 07:57:39 INFO - 'XPC_SERVICE_NAME': '0', 07:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:40 INFO - Downloading/unpacking psutil>=3.1.1 07:57:40 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 07:57:40 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 07:57:40 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 07:57:40 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 07:57:40 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 07:57:40 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 07:57:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:57:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:43 INFO - Installing collected packages: psutil 07:57:43 INFO - Running setup.py install for psutil 07:57:43 INFO - building 'psutil._psutil_osx' extension 07:57:43 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 07:57:43 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 07:57:43 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 07:57:43 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 07:57:43 INFO - building 'psutil._psutil_posix' extension 07:57:43 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 07:57:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:57:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:57:43 INFO - ^ 07:57:43 INFO - 1 warning generated. 07:57:43 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 07:57:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:57:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:57:43 INFO - Successfully installed psutil 07:57:43 INFO - Cleaning up... 07:57:43 INFO - Return code: 0 07:57:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:57:43 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')]} 07:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:43 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')]} 07:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:43 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:43 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 07:57:43 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 07:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:43 INFO - 'HOME': '/Users/cltbld', 07:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:43 INFO - 'LOGNAME': 'cltbld', 07:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:43 INFO - 'MOZ_NO_REMOTE': '1', 07:57:43 INFO - 'NO_EM_RESTART': '1', 07:57:43 INFO - 'PAGER': '/bin/cat', 07:57:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:43 INFO - 'PWD': '/builds/slave/test', 07:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:43 INFO - 'SHELL': '/bin/bash', 07:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:57:43 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:43 INFO - 'USER': 'cltbld', 07:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:43 INFO - 'XPC_FLAGS': '0x0', 07:57:43 INFO - 'XPC_SERVICE_NAME': '0', 07:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:57:44 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 07:57:44 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 07:57:44 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 07:57:44 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 07:57:44 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 07:57:44 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 07:57:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:57:46 INFO - Installing collected packages: mozsystemmonitor 07:57:46 INFO - Running setup.py install for mozsystemmonitor 07:57:46 INFO - Successfully installed mozsystemmonitor 07:57:46 INFO - Cleaning up... 07:57:46 INFO - Return code: 0 07:57:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:57:46 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')]} 07:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:46 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')]} 07:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:46 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:46 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 07:57:46 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 07:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:46 INFO - 'HOME': '/Users/cltbld', 07:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:46 INFO - 'LOGNAME': 'cltbld', 07:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:46 INFO - 'MOZ_NO_REMOTE': '1', 07:57:46 INFO - 'NO_EM_RESTART': '1', 07:57:46 INFO - 'PAGER': '/bin/cat', 07:57:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:46 INFO - 'PWD': '/builds/slave/test', 07:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:46 INFO - 'SHELL': '/bin/bash', 07:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:57:46 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:46 INFO - 'USER': 'cltbld', 07:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:46 INFO - 'XPC_FLAGS': '0x0', 07:57:46 INFO - 'XPC_SERVICE_NAME': '0', 07:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:47 INFO - Downloading/unpacking blobuploader==1.2.4 07:57:47 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 07:57:47 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 07:57:47 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 07:57:47 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 07:57:47 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 07:57:47 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 07:57:49 INFO - Downloading blobuploader-1.2.4.tar.gz 07:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:57:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:57:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:50 INFO - Downloading docopt-0.6.1.tar.gz 07:57:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:57:50 INFO - Installing collected packages: blobuploader, requests, docopt 07:57:50 INFO - Running setup.py install for blobuploader 07:57:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:57:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:57:50 INFO - Running setup.py install for requests 07:57:51 INFO - Running setup.py install for docopt 07:57:51 INFO - Successfully installed blobuploader requests docopt 07:57:51 INFO - Cleaning up... 07:57:51 INFO - Return code: 0 07:57:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:51 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')]} 07:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:51 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')]} 07:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:51 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:51 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 07:57:51 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 07:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:51 INFO - 'HOME': '/Users/cltbld', 07:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:51 INFO - 'LOGNAME': 'cltbld', 07:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:51 INFO - 'MOZ_NO_REMOTE': '1', 07:57:51 INFO - 'NO_EM_RESTART': '1', 07:57:51 INFO - 'PAGER': '/bin/cat', 07:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:51 INFO - 'PWD': '/builds/slave/test', 07:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:51 INFO - 'SHELL': '/bin/bash', 07:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:57:51 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:51 INFO - 'USER': 'cltbld', 07:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:51 INFO - 'XPC_FLAGS': '0x0', 07:57:51 INFO - 'XPC_SERVICE_NAME': '0', 07:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-KzkR8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-uB00au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ovUH0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-FtUJVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-3CnLHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-bZWFYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-tFacC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-zQF6Pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-eE6ZbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-auZN_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-VoMN6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-yl_dhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Aen9uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ivPKf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-mo7RYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-MiHNgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-tleeiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:57:53 INFO - Running setup.py install for manifestparser 07:57:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:57:53 INFO - Running setup.py install for mozcrash 07:57:53 INFO - Running setup.py install for mozdebug 07:57:54 INFO - Running setup.py install for mozdevice 07:57:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Running setup.py install for mozfile 07:57:54 INFO - Running setup.py install for mozhttpd 07:57:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Running setup.py install for mozinfo 07:57:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Running setup.py install for mozInstall 07:57:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:57:54 INFO - Running setup.py install for mozleak 07:57:54 INFO - Running setup.py install for mozlog 07:57:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:57:55 INFO - Running setup.py install for moznetwork 07:57:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:57:55 INFO - Running setup.py install for mozprocess 07:57:55 INFO - Running setup.py install for mozprofile 07:57:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:57:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:57:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:57:55 INFO - Running setup.py install for mozrunner 07:57:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:57:55 INFO - Running setup.py install for mozscreenshot 07:57:55 INFO - Running setup.py install for moztest 07:57:56 INFO - Running setup.py install for mozversion 07:57:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:57:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:57:56 INFO - Cleaning up... 07:57:56 INFO - Return code: 0 07:57:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:57:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:57:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:57:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:57:56 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:57:56 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 07:57:56 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 07:57:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:57:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:57:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:57:56 INFO - 'HOME': '/Users/cltbld', 07:57:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:57:56 INFO - 'LOGNAME': 'cltbld', 07:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:57:56 INFO - 'MOZ_NO_REMOTE': '1', 07:57:56 INFO - 'NO_EM_RESTART': '1', 07:57:56 INFO - 'PAGER': '/bin/cat', 07:57:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:57:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:57:56 INFO - 'PWD': '/builds/slave/test', 07:57:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:57:56 INFO - 'SHELL': '/bin/bash', 07:57:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:57:56 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:57:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:57:56 INFO - 'USER': 'cltbld', 07:57:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:57:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:57:56 INFO - 'XPC_FLAGS': '0x0', 07:57:56 INFO - 'XPC_SERVICE_NAME': '0', 07:57:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-9ZeGRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ulRzx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-InBnmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-9jKjYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-gpPTtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Y4eFYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-xSSeNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-bFGdBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-GcRXGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:57:57 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)) 07:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:57:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-SUFIxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-JnlctX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Zo3DzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Gceyjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-VOkdoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ubQHAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-yR_fYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:57:58 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)) 07:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:57:58 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Z4Xj5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:57:58 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)) 07:57:58 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)) 07:57:58 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)) 07:57:58 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)) 07:57:58 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)) 07:57:58 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)) 07:57:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:57:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:01 INFO - Downloading blessings-1.6.tar.gz 07:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:58:01 INFO - Installing collected packages: blessings 07:58:01 INFO - Running setup.py install for blessings 07:58:02 INFO - Successfully installed blessings 07:58:02 INFO - Cleaning up... 07:58:02 INFO - Return code: 0 07:58:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:58:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:58:02 INFO - Reading from file tmpfile_stdout 07:58:02 INFO - Current package versions: 07:58:02 INFO - blessings == 1.6 07:58:02 INFO - blobuploader == 1.2.4 07:58:02 INFO - docopt == 0.6.1 07:58:02 INFO - manifestparser == 1.1 07:58:02 INFO - mozInstall == 1.12 07:58:02 INFO - mozcrash == 0.17 07:58:02 INFO - mozdebug == 0.1 07:58:02 INFO - mozdevice == 0.48 07:58:02 INFO - mozfile == 1.2 07:58:02 INFO - mozhttpd == 0.7 07:58:02 INFO - mozinfo == 0.9 07:58:02 INFO - mozleak == 0.1 07:58:02 INFO - mozlog == 3.2 07:58:02 INFO - moznetwork == 0.27 07:58:02 INFO - mozprocess == 0.22 07:58:02 INFO - mozprofile == 0.28 07:58:02 INFO - mozrunner == 6.11 07:58:02 INFO - mozscreenshot == 0.1 07:58:02 INFO - mozsystemmonitor == 0.3 07:58:02 INFO - moztest == 0.7 07:58:02 INFO - mozversion == 1.4 07:58:02 INFO - psutil == 3.1.1 07:58:02 INFO - requests == 1.2.3 07:58:02 INFO - wsgiref == 0.1.2 07:58:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:02 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:58:02 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 07:58:02 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 07:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:02 INFO - 'HOME': '/Users/cltbld', 07:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:02 INFO - 'LOGNAME': 'cltbld', 07:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:02 INFO - 'MOZ_NO_REMOTE': '1', 07:58:02 INFO - 'NO_EM_RESTART': '1', 07:58:02 INFO - 'PAGER': '/bin/cat', 07:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:02 INFO - 'PWD': '/builds/slave/test', 07:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:02 INFO - 'SHELL': '/bin/bash', 07:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:58:02 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:02 INFO - 'USER': 'cltbld', 07:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:02 INFO - 'XPC_FLAGS': '0x0', 07:58:02 INFO - 'XPC_SERVICE_NAME': '0', 07:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 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)) 07:58:02 INFO - Cleaning up... 07:58:02 INFO - Return code: 0 07:58:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:58:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:58:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:02 INFO - 'HOME': '/Users/cltbld', 07:58:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:02 INFO - 'LOGNAME': 'cltbld', 07:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:02 INFO - 'MOZ_NO_REMOTE': '1', 07:58:02 INFO - 'NO_EM_RESTART': '1', 07:58:02 INFO - 'PAGER': '/bin/cat', 07:58:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:02 INFO - 'PWD': '/builds/slave/test', 07:58:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:02 INFO - 'SHELL': '/bin/bash', 07:58:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:58:02 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:58:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:02 INFO - 'USER': 'cltbld', 07:58:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:02 INFO - 'XPC_FLAGS': '0x0', 07:58:02 INFO - 'XPC_SERVICE_NAME': '0', 07:58:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:58:02 INFO - Downloading/unpacking jsonschema 07:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:58:05 INFO - Downloading/unpacking functools32 (from jsonschema) 07:58: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 07:58: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 07:58: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 07:58: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 07:58: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 07:58: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 07:58:05 INFO - Downloading functools32-3.2.3-2.tar.gz 07:58:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:58:05 INFO - warning: no files found matching '*.txt' 07:58:05 INFO - no previously-included directories found matching 'build' 07:58:05 INFO - no previously-included directories found matching 'dist' 07:58:05 INFO - no previously-included directories found matching '.git*' 07:58:05 INFO - Installing collected packages: jsonschema, functools32 07:58:05 INFO - Running setup.py install for functools32 07:58:05 INFO - warning: no files found matching '*.txt' 07:58:05 INFO - no previously-included directories found matching 'build' 07:58:05 INFO - no previously-included directories found matching 'dist' 07:58:05 INFO - no previously-included directories found matching '.git*' 07:58:06 INFO - Successfully installed jsonschema functools32 07:58:06 INFO - Cleaning up... 07:58:06 INFO - Return code: 0 07:58:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:58:06 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')]} 07:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:58:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:06 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')]} 07:58:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:58:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:06 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 0x108867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10886d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb13ac86690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, '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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 07:58:06 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 07:58:06 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 07:58:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:58:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:06 INFO - 'HOME': '/Users/cltbld', 07:58:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:06 INFO - 'LOGNAME': 'cltbld', 07:58:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:06 INFO - 'MOZ_NO_REMOTE': '1', 07:58:06 INFO - 'NO_EM_RESTART': '1', 07:58:06 INFO - 'PAGER': '/bin/cat', 07:58:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:06 INFO - 'PWD': '/builds/slave/test', 07:58:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:06 INFO - 'SHELL': '/bin/bash', 07:58:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:58:06 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:58:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:06 INFO - 'USER': 'cltbld', 07:58:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:06 INFO - 'XPC_FLAGS': '0x0', 07:58:06 INFO - 'XPC_SERVICE_NAME': '0', 07:58:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:58:06 INFO - Downloading/unpacking flake8 07:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:58:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:58:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:58: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 07:58: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 07:58: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 07:58: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 07:58: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 07:58: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 07:58:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:58: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 07:58: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 07:58: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 07:58: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 07:58: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 07:58: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 07:58:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:58:10 INFO - no previously-included directories found matching 'docs/_build' 07:58:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:58:10 INFO - Running setup.py install for pep8 07:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:58:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:58:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:58:10 INFO - no previously-included directories found matching 'docs/_build' 07:58:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:58:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:58:10 INFO - Cleaning up... 07:58:10 INFO - Return code: 0 07:58:10 INFO - Running post-action listener: _resource_record_post_action 07:58:10 INFO - Running post-action listener: _start_resource_monitoring 07:58:10 INFO - Starting resource monitoring. 07:58:10 INFO - ##### 07:58:10 INFO - ##### Finished create-virtualenv step (success) 07:58:10 INFO - ##### 07:58:10 INFO - ##### 07:58:10 INFO - ##### Running install step. 07:58:10 INFO - ##### 07:58:10 INFO - Running pre-action listener: _resource_record_pre_action 07:58:10 INFO - Running main action method: install 07:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:58:10 INFO - Reading from file tmpfile_stdout 07:58:10 INFO - Detecting whether we're running mozinstall >=1.0... 07:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:58:10 INFO - Reading from file tmpfile_stdout 07:58:10 INFO - Output received: 07:58:10 INFO - Usage: mozinstall [options] installer 07:58:10 INFO - Options: 07:58:10 INFO - -h, --help show this help message and exit 07:58:10 INFO - -d DEST, --destination=DEST 07:58:10 INFO - Directory to install application into. [default: 07:58:10 INFO - "/builds/slave/test"] 07:58:10 INFO - --app=APP Application being installed. [default: firefox] 07:58:10 INFO - mkdir: /builds/slave/test/build/application 07:58:10 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'] 07:58:10 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 07:58:45 INFO - Reading from file tmpfile_stdout 07:58:45 INFO - Output received: 07:58:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:58:45 INFO - Running post-action listener: _resource_record_post_action 07:58:45 INFO - ##### 07:58:45 INFO - ##### Finished install step (success) 07:58:45 INFO - ##### 07:58:45 INFO - ##### 07:58:45 INFO - ##### Running run-tests step. 07:58:45 INFO - ##### 07:58:45 INFO - Running pre-action listener: _resource_record_pre_action 07:58:45 INFO - Running main action method: run_tests 07:58:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:58:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:58:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:58:45 INFO - 2016-06-02 07:58:45.444 screenresolution[1932:11876] starting screenresolution argv=screenresolution get 07:58:45 INFO - 2016-06-02 07:58:45.447 screenresolution[1932:11876] Display 0: 1600x1200x32@60 07:58:45 INFO - 2016-06-02 07:58:45.452 screenresolution[1933:11879] starting screenresolution argv=screenresolution list 07:58:45 INFO - Available Modes on Display 0 07:58:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:58:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:58:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:58:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:58:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:58:45 INFO - Intel Iris: 07:58:45 INFO - Chipset Model: Intel Iris 07:58:45 INFO - Type: GPU 07:58:45 INFO - Bus: Built-In 07:58:45 INFO - VRAM (Dynamic, Max): 1536 MB 07:58:45 INFO - Vendor: Intel (0x8086) 07:58:45 INFO - Device ID: 0x0a2e 07:58:45 INFO - Revision ID: 0x0009 07:58:45 INFO - Displays: 07:58:45 INFO - Display: 07:58:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:58:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:58:45 INFO - Display Serial Number: 1600x1200 60 07:58:45 INFO - Main Display: Yes 07:58:45 INFO - Mirror: Off 07:58:45 INFO - Online: Yes 07:58:45 INFO - Rotation: Supported 07:58:45 INFO - Return code: 0 07:58:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:58:45 INFO - Python 2.7.3 07:58:45 INFO - Return code: 0 07:58:45 INFO - grabbing minidump binary from tooltool 07:58: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')]} 07:58:45 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 0x10870bd30>, '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 0x10852fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108702b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:58:45 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 07:58:45 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 07:58:45 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 07:58:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:58:45 INFO - Return code: 0 07:58:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:58:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:58:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:58:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:58:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:58:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:58:46 INFO - Return code: 0 07:58:46 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/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 07:58:46 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/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --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 07:58:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XFRt7LWhO9/Render', 07:58:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:46 INFO - 'HOME': '/Users/cltbld', 07:58:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:46 INFO - 'LOGNAME': 'cltbld', 07:58:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:58:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:46 INFO - 'MOZ_NO_REMOTE': '1', 07:58:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:58:46 INFO - 'NO_EM_RESTART': '1', 07:58:46 INFO - 'PAGER': '/bin/cat', 07:58:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:46 INFO - 'PWD': '/builds/slave/test', 07:58:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:58:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:46 INFO - 'SHELL': '/bin/bash', 07:58:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XoJ8kBQ6XH/Listeners', 07:58:46 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 07:58:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:46 INFO - 'USER': 'cltbld', 07:58:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:46 INFO - 'XPC_FLAGS': '0x0', 07:58:46 INFO - 'XPC_SERVICE_NAME': '0', 07:58:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:46 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/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 07:58:47 INFO - mozversion application_buildid: 20160602060755 07:58:47 INFO - mozversion application_changeset: 92e0c73391e71a400e2c6674bca5ca70804ab081 07:58:47 INFO - mozversion application_display_name: Nightly 07:58:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:58:47 INFO - mozversion application_name: Firefox 07:58:47 INFO - mozversion application_remotingname: firefox 07:58:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:58:47 INFO - mozversion application_vendor: Mozilla 07:58:47 INFO - mozversion application_version: 49.0a1 07:58:47 INFO - mozversion platform_buildid: 20160602060755 07:58:47 INFO - mozversion platform_changeset: 92e0c73391e71a400e2c6674bca5ca70804ab081 07:58:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:58:47 INFO - mozversion platform_version: 49.0a1 07:58:47 INFO - using testdate: 1464879527 07:58:47 INFO - actual date: 1464879527 07:58:47 INFO - starting webserver on 'localhost:50333' 07:58:47 INFO - SUITE-START | Running 2 tests 07:58:47 INFO - TEST-START | dromaeo_css 07:58:47 INFO - operating with platform_type : mac_ 07:58:47 INFO - Initialising browser for dromaeo_css test... 07:58:47 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpL7VU3q/profile http://localhost:50333/getInfo.html) 07:58:49 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 07:58:49 INFO - PROCESS | 1951 | colorDepth:24 07:58:49 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 07:58:49 INFO - PROCESS | 1951 | __metrics 07:58:49 INFO - PROCESS | 1951 | 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" 07:58:49 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:58:49 INFO - TEST-INFO | 1951: exit 0 07:58:49 INFO - Browser initialized. 07:58:49 INFO - Running cycle 1/1 for dromaeo_css test... 07:58:49 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpL7VU3q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:59:01 INFO - PROCESS | 1953 | RSS: Main: 242065408 07:59:01 INFO - PROCESS | 1953 | 07:59:53 INFO - PROCESS | 1953 | 1464879593857 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:02:04 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50333/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50333/tests/dromaeo/cssquery-ext.html) 08:02:06 INFO - PROCESS | 1953 | RSS: Main: 458969088 08:02:06 INFO - PROCESS | 1953 | 08:05:10 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50333/tests/dromaeo/cssquery-ext.html (next: http://localhost:50333/tests/dromaeo/cssquery-jquery.html) 08:05:10 INFO - PROCESS | 1953 | RSS: Main: 380952576 08:05:10 INFO - PROCESS | 1953 | 08:08:12 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50333/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50333/tests/dromaeo/cssquery-mootools.html) 08:08:13 INFO - PROCESS | 1953 | RSS: Main: 374108160 08:08:13 INFO - PROCESS | 1953 | 08:11:15 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50333/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50333/tests/dromaeo/cssquery-prototype.html) 08:11:16 INFO - PROCESS | 1953 | RSS: Main: 372535296 08:11:16 INFO - PROCESS | 1953 | 08:14:19 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50333/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50333/tests/dromaeo/cssquery-yui.html) 08:14:20 INFO - PROCESS | 1953 | RSS: Main: 351801344 08:14:20 INFO - PROCESS | 1953 | 08:17:23 INFO - PROCESS | 1953 | Cycle 1(1): loaded http://localhost:50333/tests/dromaeo/cssquery-yui.html (next: http://localhost:50333/tests/dromaeo/cssquery-dojo.html) 08:17:24 INFO - PROCESS | 1953 | RSS: Main: 378077184 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | __start_tp_report 08:17:24 INFO - PROCESS | 1953 | _x_x_mozilla_page_load 08:17:24 INFO - PROCESS | 1953 | _x_x_mozilla_page_load_details 08:17:24 INFO - PROCESS | 1953 | |i|pagename|runs| 08:17:24 INFO - PROCESS | 1953 | |0;dojo.html;3399.269157131216;3407.1652445150944;3435.8453869575865;3453.9136521586943;3474.722022238221;5474.863128421792;5565.6660600364;5566.053770858954;5633.408492108326;5671.2060311556315;5586.24585680933;5639.68981706006;5641.210230567724;5643.830685079433;5655.660660360381;5089.770960306777;5114.360704911886;5126.410462796789;5144.6655967362185;5204.089284375238;4155.376693495969;5205.869853253661;5208.270842082112;5211.7133557654315;5217.504337087989;5038.420581633122;5091.516305950929;5120.641555091145;5137.948620513803;5143.588512918957;4297.205017071852;5210.817621383271;5222.582193424517;5223.164293712987;5272.261883336336;5046.899062018776;5048.242763585455;5113.923291150636;5139.203423469368;5143.819966301162;5274.2879711238875;5302.787888484456;5457.317835270591;5555.333359996826;5586.35756887959;5798.710064496759;5830.737616807254;5853.88292234153;5904.321003084658;5904.940950590525;5947.583669143161;5951.821445356646;5973.492253158487;5998.40015998401;6126.540509461764;5684.488396044376;5760.251167348218;5772.826815195551;5797.942020579824;5805.419458054161;6231.532635052389;6235.220597425322;6256.968715156486;6264.060390941395;6281.780137695158;5907.9704601476715;5977.073553599199;6108.461860771425;6109;6110.564017455894;6157.983932651152;6210.130581718563;6210.968945155245;6226;6231.096491008869;6141.662208578571;6154.169187159766;6156.168917196122;6162.137300777895;6162.938370616236;5940.732667029994;6086.786962456293;6108.969455152695;6145.631262124287;6165.290991535941;4861.854144375604;4871;4887.3890763654545;4887.877803054952;4893.119238537098;4372.278574035312;4379.715318504287;4401.317795741666;4429.402030725875;4455.732656040648;5655.915161272585;5664.178694089334;5714.0571805652435;5719.7712091516705;5720.256366672306;6203.8138855834395;6220.284667263322;6224.906626400608;6230.59501132425;6232.594881332699;5546.611737178439;5649;5652;5657.717114144276;5663.9716801415725;5283.07546179417;5326;5355.919661205085;5358.758855851496;5359.981603495401;6186.412290832363;6262.405071518198;6267.1539342188225;6268.843278918063;6325.936740632626;6211.81364559064;6242.28213755424;6248.031555108965;6255.46828519581;6266.122742815918;6203.937960620517;6256.530760192913;6256.749730010749;6258.998560230341;6261.279952805304;6098.512119030555;6127.724252408563;6130.570860039755;6131.202943617302;6132.35610260934;6104.06507294887;6169.1054797053275;6201.317855036033;6239.968800156152;6253.5935164216;5968.31364393083;6058.0610005449225;6063.9696801517475;6074.37914009963;6078.392160784063;5796.188533605383;6032.788852390146;6044.455998960115;6047.78832740852;6065.7876974307455;4845;5720.19917211599;5772.24960755116;5789.073748200268;5798.420157984337;5781.190633311424;5875;5915.053591425523;5921.2006379138265;5952.821415357546;5798.59409841307;5817;5883.558733094949;5894.322152952465;5979.491743201703 08:17:24 INFO - PROCESS | 1953 | |1;ext.html;482.96136309095255;483.3909274314363;487.3566891702949;520.3599572525793;542.4006472847503;24520.509589808193;31788.841055794717;31789.887353942588;41957.7902110489;42684.71945841622;44491.11017779642;44654;44955.550444495544;44994.55005449944;45048.77475612617;9402.67090651829;9653.662121825737;9664.661736839213;9686.225101991859;9701.902980970206;8574.657013719443;8797.692080777175;9316.953415232949;9842.769653793275;11102.222844400896;11643.767124657501;11740.29558226505;11744.647660570197;11749.647510574696;11752.118591105658;8570.871436928452;11744.41277936104;11853.762924741457;11870.940645296718;11890.464929078213;11287.040601548879;11683.41582920859;11700.765984680258;11909.523619055228;11938.462769175321;40803;41234.793826030975;41285;41409.79295103535;41562;3545.3888515112953;3592.8742494012586;3603.531540899666;3609;3662.8351724172207;26529.336766580796;31386;31922.6807731922;32627.836860815543;32657;3269.5749552558254;3360.5295258663805;3374.105861946586;3390.762646614738;3399.711024562915;509408;509422;509699;509943.9999999963;510169;61749;62179.378206218265;62281.688591556755;62287.39413452811;62321.68839155775;65237;65341;65423.345766542676;65470.34529654738;65529.672351637935;14941.70116597662;15841.970271932518;16034.75947860783;16063.277152528164;16104;11673.824892626617;12081.395930203455;12091.972182364598;12095.274283543016;12755.936220319025;16438.671226575403;16869.493915182564;16938.49184524466;16977.40579079751;16989.575260618338;6469.644169570624;6915.031895534629;6997.580145191305;7053.894191587027;7066.611336376446;803.7387848948998;1005.0753306957616;1070.822095694745;1113.8997490225756;1192.1538944191832;24313.75686243115;28181.859090704416;31694.11928235976;32366.676333236835;32960.01119966405;1012.2964539645023;1115.9944200279108;1136.8863113688565;1139.2594813371375;1166.393475392791;15128.716822989467;15635.26382882991;15639.3744250231;15684.137372444397;16025.358985640676;660243;665818;672973;673467;673640;57985.71007144937;58275;58362.708186458796;58437;58560.4143958555;1255.2280347586168;1315.5658632651393;1386.4662105089412;1394.5119208277224;1411.7529432349297;28377.432451350443;30024.849875750482;32092.588355830616;32712;33204;58338.83322333614;58557.70721146367;59390;59409;59533.40466595452;571383;577182;578771;579850;581530;23805.285841424786;26167.345816354744;26470.735292646827;26599;26995.59506607363;37299;40806;41337.379939301536;44582.777086114365;50580.747096264284;40128;40911.59088409078;41558.273860075;41975.58024419839;42660.57339426566;40060.399094014196;40289.99275018148;40367.659540475455;40424;41344.96637584084;31464.68535314709;38832.223355532165;39144.82565523039;39248;39289.607103928596;34002.97991060272;34355.82822085873;34412.48381274233;34937.65062349445;35017.299654007285;25545.872270638527;25798.742012580384;25800.61299080553;26298.795421602284;26442.206733798015 08:17:24 INFO - PROCESS | 1953 | |2;jquery.html;371.6636444018161;383.7946698516294;384.61922696530434;389.6882494004797;412.7420362273579;18747;21900.342989710334;21984.01071951762;22138.889305553468;22169.334919952387;20205.898970505164;20464.795352046476;21045.894770526163;21168.4707882303;21314.57370852582;5153.665011774223;5770.249867517227;5889.911651325234;5991.891500072479;6002.969985150068;5851.970740146293;5907.822765317026;5920.230370051887;5942.316633587128;5952.255968003999;5679.091345384739;5836.2121113649755;5839.941600583982;5846.093855452412;5872.207252020987;5529.502344788948;5843.298804143508;5851.15158302046;5851.941480585182;5886.823395298148;5131.486851314876;5153.175491921274;5163.277141200235;5165.457626949192;5174.404943431516;25500.48999020009;25738.485230295475;26159.869200654066;26312.47375052488;26435.33911652205;2412.541617092747;2601.297649634588;3090.505519116953;3146.763992700534;3205.0705295464286;14781.926090369587;14785.630359240997;15215.467458639005;15513.844861551466;15516.456924007718;1856.6163688199606;1898.5538398476347;1908.3225454963565;1927.5084853362303;2017.8486613503899;111888;117569.41215293869;117785;117962;118997.8100219004;10710.089642380484;10859.83710244347;11131.499082541304;11145.609903653332;11284.266522676;13735.656608584864;13868.86131138696;13868.86131138696;13894.861051389558;13904.930475347559;8490.363222758318;8667.826643467095;8695.13048695138;8804.031556528776;8809.515476648718;7191.89212161818;8709.651613935372;8713.869291960626;8722.738317850475;8823;6638.96680516604;6711.664416779142;6746.730130794714;6762.72949082041;6769.172323472635;1843.824836640517;1868.4768264885854;1871.6537440573265;1880.2385034061226;1897.5066482714594;921.5392303848076;1016.2835201183149;1018.8369860822232;1049.8267785815256;1049.8635177427038;18668.78652887585;20289.898550507154;21072.578548428944;21758.673619895715;21834.672479912813;987.5062468765617;998.1116806042628;1000.7448100734412;1007.7934023525306;1089.2429761315968;11166.944165279121;11631.76736465266;11740.765184696258;11755.764884702257;11757.529698812123;580658;588912;601436;602199;611237.887621127;87966.56016719875;116697;119613;132266;136761.94857077152;989.7179303898498;998.5406712911996;999.3304485994402;1001.9549120289604;1002.0530598584413;18649.720254195927;18830.52923676864;19114.33099841499;19123.330683426015;19129.33047343336;39083.80458097691;56009.439905600426;56035.59911002257;56140.71929640489;56754.716226420256;74749;77209.61395192987;79437.60281198558;82199.17800821915;84466.57766711127;7098.654886978702;8010.556393364451;8097.663885458834;8270.669173233244;8291.295239904675;10452;12589.307588082744;12935.547255846377;13104.475820967054;13199.142055766344;11004.119670426508;11084.61203857861;11114.44427778624;11152.776944461228;11301.943490282496;10883.564657413615;11009.669709908716;11041.447927603427;11050.392228427514;11205.66383008511;11326.886731132583;11345.773084538097;11439.885601143882;11482.196246262682;11746.236494627821;6347.39699728525;8114.269715725613;8143.267105960492;8162.387820913574;8348.165183481846;8227.670893164206;10223;11231.887681123084;11308.886911130783;11340.376279304717 08:17:24 INFO - PROCESS | 1953 | |3;mootools.html;1388.3544151969331;1424.9715005699886;1471.8233811942569;1513.969720605588;1972.3294080012784;7789.376849852012;8531.080506885948;8734.257588105003;8744.519051452176;8820.470771753684;8412.369072319585;8669.089745576719;8676.175763302475;8700.738977830675;8702.173293537104;1843.4008947092182;2103.095668862396;2178.56428714257;2325.2210509479346;2361.8937147828387;2179.8583092099043;2204.8677079375207;2333.218371845434;2413.903443862252;2416.9516609667794;2373.074500944633;2376.061455724988;2377.8454400463916;2379.5597814404305;2385.8926348314367;2324.0936034946385;2377.4413012941945;2377.548265829487;2380.797632201248;2382.7140743110763;1969.7885800232898;2097.4861159015986;2340.2277248507953;2379.845310054841;2401.135591187171;8646.178613031752;8730.825383492294;8731.650733970633;8747.21275085239;8747.737567872973;5199.220116982445;5377.0321342158395;5379.300690910196;5386.13821788512;5409.486098820645;3508.9298214035575;3617.5116359291424;3670.889873303805;3681.852725890961;3684.0973961379364;1065.2702898514465;1096.6874440784418;1103.4151899493281;1106.0156460749859;1118.429600903537;11701.00541453969;11732.765344693144;11742.236754611009;11748.706282342924;11750.58872939443;4607.562281583244;4762.690425122356;4817.084753896748;4846.684965477232;4858.5384388483035;9952.402855828674;9959;9959.452230127412;9963.750906227257;9965.003499649976;5365.1147560652835;5372.274742909735;5373.301470808848;5382.058139825514;5402.18967154922;4469.642428605704;4769.642276829251;4850.102730994778;4942.950570494321;4944.456109827916;3621.8732344367822;3791.696664266852;3809;3869.955112119712;3901.180752042046;1167.6730515455686;1168.9064874809994;1175.3241885915634;1179.9646010619695;1180.7933611617932;792.4334101117613;824.7113510271357;880.7665968518347;886.7054101012594;984.9704508864745;5883.882322353529;5973;5994;6077.817665470043;6198.318184999646;917.7698193390127;968.8837339519303;998.4508520313798;999.5801763259449;1038.076112260089;3924.234774219;4044.1507283470214;4079.5104587449696;4112.773797441169;4114.382842573638;8632.956835215784;10512.632057877938;10695.304805187638;10702.25084244111;10704.1436685065;10599.682009539727;10720.624778132727;10732.463376831127;10747.408892510986;10754.946225268823;7551.433642476836;8075.596220188967;8250.54621995796;8329.791755206046;8350.164983501601;5852.707364631837;5875.588708790515;5932.288125424977;5965.492933100735;5990.580659353804;10002.849957250794;10182.49087545605;10189.949050254998;10201.846972295272;10202.38785672862;8919.643214271357;9826.96816834222;9981.500924953869;9999.400035997864;10275.640352587623;7069.646517674199;7279.27207279289;7511.924880751122;7709.151993280622;7728.26581474759;8129.715459958873;8392.538410387488;8404.747857564284;8504.979402471743;8537.359698022798;7292.598907060163;7461.626918653937;7499.62501874915;7620.961895190489;7632.465727399029;7159.176694680178;7365.226651201761;7393.334599886036;7436.442266829901;7436.665350059368;7033.296670332926;7083.504154709327;7127.429805615642;7136.7145314186855;7146.571205727673;4482.350059241327;4903.686441728353;4963.553280204799;5105.693658380361;5148.639595228298;5067.214581739836;5126.7949282029795;5141.871453213699;5164.04465173929;5272.920906186487 08:17:24 INFO - PROCESS | 1953 | |4;prototype.html;253.3665835411471;296.95397213431926;309.15600410878295;314.762354422411;315.4054607065681;4946.282788995593;5129.53834154926;5147.7645365112385;5158.303629010083;5298.483220470274;4933.939203071339;5063.265826455162;5141.614378921587;5166.225066240066;5168.48315168483;3351.497275408684;3902.8633997810084;3917.6102577427746;3954.644082032624;3966.266240745457;3231.515272709089;3526.8589256429714;3935.783531905744;3995.9200815983663;3999.7600743769526;3281.671832816723;3316.535685004101;3944.4872166618443;3947.1710940702487;3948.644621984021;3888.6111388861173;3892.377219644857;3911.784851833148;3916.980415097935;3966.3058964681354;3069.8311592862383;3419.0426680529245;3423.4864770284407;3444.9311013779584;3451.9299017304465;4801.871560183354;4913.361263035782;4918.754062296906;4937.333839726543;5023.748812559357;4464.598186163228;4480.843170489017;4484.663650226214;4486.932696009562;4493.932591011137;4388.539203383631;4422.181896349186;4714.905701885943;4955.36472963921;5331.5201631853315;3216.726578240852;3619.60184379718;3785.7728536287636;3791.393377059685;3800.9737370909975;5372.409035006146;5606.887862242773;5611.8316450506145;5662.405447428002;5733.283339582552;3409.4316614357176;3845.576986531479;3864.265789499986;3914.3541315683165;3945.6054394560315;3044.802087864282;3426.52028715975;3429.8456569453933;3650.415933450688;3779.962200378016;3555.8577656893954;4474.986256184665;4484.573965473275;4487.237169681162;4496.842610508616;5346.385165705994;5383.353997520323;5437.8368648940595;5440.809571664972;5515.834524964257;1210.9273443593413;1235.6416639174718;1247.7941139711845;1292.3473645808806;1301.1932601786953;744.3858816476428;758.5714071549556;763.9465237433326;788.3102285500187;823.5546615689427;3682.5028621135802;3954.9604503954592;4081.081891510985;4151.045259590311;4155.625993660585;3976.4035394691027;4072.6741860651077;4126.051008268115;4351.891202719958;4686.929696054562;3360.572216339369;3661.6085887362633;3979.905525980321;4021.2761702893176;4082.448669505569;2114.6149272226717;2154.849160558746;2267.7505474397803;2271.273192578359;2430.2223288547493;4491.416115904912;4537.413674205671;4858.58702010326;4873.9025219496125;4886.193778026586;4160.500739911169;4673.3690951721765;4722.197226471508;4793.304970779218;4841.869577385939;4024.6176613221696;4294.828206871753;4361.433013708198;4370.300751879684;4506.864794056183;3848.499695039627;4097.242010228118;4136.187392573079;4530.773461326987;4562.498125206291;4021.9086075241294;4105.023857703141;4480.572362143029;4576.780261573068;4669.221813021247;4100.052475074316;4291.721038132636;4694.474295853929;4720.749001514533;4846.5638092571835;2764.156932135718;2942.985285073561;3185.888493902702;3320.9169770755925;3349.6650334966307;4299.355096735515;4599.653327605381;4677.3451716759;4677.438707355139;4684.531546845425;4195.622393984556;4545.454545454567;5328.653637513549;5413.079776438094;5482.725863706878;4362.2987035056985;4865.343178670834;4890.628045397908;5261.89476210466;5293.914747476681;4907.312976183409;4964.882901347168;5022.15804909252;5254.42201357858;5278.683279003272;5340.635399698165;5356.5982551308025;5364.731763411892;5372.731363431891;5409.323834520685;4096.037431203606;4399.658104278225;5264.736763161904;5312.309399778004;5338.97330513345 08:17:24 INFO - PROCESS | 1953 | |5;yui.html;705.3334598804131;714.8856183010716;766.8811334243194;785.5365334452672;794.3089512124452;15421.691566168683;15458.995165314262;15625.374985000615;15770.053796772187;15790.526284211463;14478.348474318655;14610.561683149523;14612.050216735905;14620.561383158521;14628.4880029199;902.503623007345;911.5578944212052;921.3320342751485;922.6862866625346;923.6490133749165;718.8526352097807;721.3507842941368;746.2462912458402;915.0803442540238;920.4201353147541;960.0159836167913;960.8894977077621;963.6964356227766;963.8457846744523;965.0542468380991;743.6542007966268;950.9762255943588;953.9904600953971;954.0364232125534;955.5270141280076;699.4753934549088;714.7033980897921;715.9033530473372;719.4963525532096;721.3651986252117;15114.848851511455;15144.772828407582;15156.545303640798;15190.924045379812;15194.544163675107;13798.241096739775;13986.020978531507;14066;14094.436222551085;14304.141751494839;12809.295488748116;12859.16415432994;12911.67720806978;13061.346932653329;13380.19718816874;4575.700115960178;4580.358749775034;4615.330777037344;4682.695624784412;4784.306275590055;104076;105681;107534;107707.46146269297;109123.36314955202;11101.72245693848;11110.723383466408;11274.492647830868;11283.210175287815;11484.023857971999;22304;22325.441863953532;22327;22358.88820555887;22381.77618223797;7870.055593328837;8014.318782903519;8064.596770161468;8082.19178082187;8094.352451803841;4491.842785502492;4496.595306422372;4526.864194074183;4578.771061446914;4654.208784506574;3480.564929383827;3596.6403359663823;3603.801790901473;3607.981960090235;3616.6564176403726;593.9406059394113;612.9295131059896;639.7888696730068;639.8336432527483;654.6628486329544;537.4410612962554;559.2506041903869;632.2476253258608;635.3138610300864;643.9710213040424;8897.599608017656;9249.676261330822;9295.349325547148;9329.210109558458;9445.433274003582;532.9307190065344;534.4094775273345;570.6034306157181;622.8011078673626;656.2256537285953;3730.3285408626707;4307.590778876001;4405.7797110144365;4538;4562.475315338778;89259.66110508348;115632.42183789027;116452;121368.39315803365;126434;105315.13157568256;108717.45641271742;110763.44618276857;111339;113099.43450282696;90865;93094;93126;93832.53083734674;94412;9587.376820506644;9817.754556136153;9821.65624203178;9823.21414286841;9840.950795245977;45178.096438070395;53193.67015824635;56560.43439565552;58166.7091664539;58912.23263302108;83361.3327733454;86549.70175447248;86861.56569217113;87781;89023;12809.159087282576;13259.469621215043;13494.392752326365;13640.113392629448;13834.23911684867;14349.64125896861;14458.855411446171;14503.637409064435;14694.55916322512;15434.382624695336;13494.190348579117;13694;13707.246101464116;13714.38285277145;13859.307034648025;13865.376058077209;14026.368813403216;14250.928745356206;14316.284185790877;14328.641783955067;13266.53567125146;13364.665883352995;13518.188908665512;13525.59423217305;14098.083624564371;11152.609658661908;11518.82721759191;11530.769384612093;11568.074554035826;11620.767584648429;9286.885573731195;9569.186619137452;9625.614975401186;10192.133668637953;11582.594609188638 08:17:24 INFO - PROCESS | 1953 | __end_tp_report 08:17:24 INFO - PROCESS | 1953 | __start_cc_report 08:17:24 INFO - PROCESS | 1953 | _x_x_mozilla_cycle_collect,2255 08:17:24 INFO - PROCESS | 1953 | __end_cc_report 08:17:24 INFO - PROCESS | 1953 | __startTimestamp1464880644019__endTimestamp 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | ------- Summary: start ------- 08:17:24 INFO - PROCESS | 1953 | Number of tests: 6 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | [#0] dojo.html Cycles:165 Average:5651.03 Median:5798.71 stddev:633.59 (10.9%) stddev-sans-first:610.41 08:17:24 INFO - PROCESS | 1953 | Values: 3399.3 3407.2 3435.8 3453.9 3474.7 5474.9 5565.7 5566.1 5633.4 5671.2 5586.2 5639.7 5641.2 5643.8 5655.7 5089.8 5114.4 5126.4 5144.7 5204.1 4155.4 5205.9 5208.3 5211.7 5217.5 5038.4 5091.5 5120.6 5137.9 5143.6 4297.2 5210.8 5222.6 5223.2 5272.3 5046.9 5048.2 5113.9 5139.2 5143.8 5274.3 5302.8 5457.3 5555.3 5586.4 5798.7 5830.7 5853.9 5904.3 5904.9 5947.6 5951.8 5973.5 5998.4 6126.5 5684.5 5760.3 5772.8 5797.9 5805.4 6231.5 6235.2 6257.0 6264.1 6281.8 5908.0 5977.1 6108.5 6109.0 6110.6 6158.0 6210.1 6211.0 6226.0 6231.1 6141.7 6154.2 6156.2 6162.1 6162.9 5940.7 6086.8 6109.0 6145.6 6165.3 4861.9 4871.0 4887.4 4887.9 4893.1 4372.3 4379.7 4401.3 4429.4 4455.7 5655.9 5664.2 5714.1 5719.8 5720.3 6203.8 6220.3 6224.9 6230.6 6232.6 5546.6 5649.0 5652.0 5657.7 5664.0 5283.1 5326.0 5355.9 5358.8 5360.0 6186.4 6262.4 6267.2 6268.8 6325.9 6211.8 6242.3 6248.0 6255.5 6266.1 6203.9 6256.5 6256.7 6259.0 6261.3 6098.5 6127.7 6130.6 6131.2 6132.4 6104.1 6169.1 6201.3 6240.0 6253.6 5968.3 6058.1 6064.0 6074.4 6078.4 5796.2 6032.8 6044.5 6047.8 6065.8 4845.0 5720.2 5772.2 5789.1 5798.4 5781.2 5875.0 5915.1 5921.2 5952.8 5798.6 5817.0 5883.6 5894.3 5979.5 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | [#1] ext.html Cycles:180 Average:72097.43 Median:3606.27 stddev:157494.58 (4367.2%) stddev-sans-first:157844.61 08:17:24 INFO - PROCESS | 1953 | Values: 483.0 483.4 487.4 520.4 542.4 24520.5 31788.8 31789.9 41957.8 42684.7 44491.1 44654.0 44955.6 44994.6 45048.8 9402.7 9653.7 9664.7 9686.2 9701.9 8574.7 8797.7 9317.0 9842.8 11102.2 11643.8 11740.3 11744.6 11749.6 11752.1 8570.9 11744.4 11853.8 11870.9 11890.5 11287.0 11683.4 11700.8 11909.5 11938.5 40803.0 41234.8 41285.0 41409.8 41562.0 3545.4 3592.9 3603.5 3609.0 3662.8 26529.3 31386.0 31922.7 32627.8 32657.0 3269.6 3360.5 3374.1 3390.8 3399.7 509408.0 509422.0 509699.0 509944.0 510169.0 61749.0 62179.4 62281.7 62287.4 62321.7 65237.0 65341.0 65423.3 65470.3 65529.7 14941.7 15842.0 16034.8 16063.3 16104.0 11673.8 12081.4 12092.0 12095.3 12755.9 16438.7 16869.5 16938.5 16977.4 16989.6 6469.6 6915.0 6997.6 7053.9 7066.6 803.7 1005.1 1070.8 1113.9 1192.2 24313.8 28181.9 31694.1 32366.7 32960.0 1012.3 1116.0 1136.9 1139.3 1166.4 15128.7 15635.3 15639.4 15684.1 16025.4 660243.0 665818.0 672973.0 673467.0 673640.0 57985.7 58275.0 58362.7 58437.0 58560.4 1255.2 1315.6 1386.5 1394.5 1411.8 28377.4 30024.8 32092.6 32712.0 33204.0 58338.8 58557.7 59390.0 59409.0 59533.4 571383.0 577182.0 578771.0 579850.0 581530.0 23805.3 26167.3 26470.7 26599.0 26995.6 37299.0 40806.0 41337.4 44582.8 50580.7 40128.0 40911.6 41558.3 41975.6 42660.6 40060.4 40290.0 40367.7 40424.0 41345.0 31464.7 38832.2 39144.8 39248.0 39289.6 34003.0 34355.8 34412.5 34937.7 35017.3 25545.9 25798.7 25800.6 26298.8 26442.2 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | [#2] jquery.html Cycles:180 Average:34976.20 Median:22154.11 stddev:99404.93 (448.7%) stddev-sans-first:99649.83 08:17:24 INFO - PROCESS | 1953 | Values: 371.7 383.8 384.6 389.7 412.7 18747.0 21900.3 21984.0 22138.9 22169.3 20205.9 20464.8 21045.9 21168.5 21314.6 5153.7 5770.2 5889.9 5991.9 6003.0 5852.0 5907.8 5920.2 5942.3 5952.3 5679.1 5836.2 5839.9 5846.1 5872.2 5529.5 5843.3 5851.2 5851.9 5886.8 5131.5 5153.2 5163.3 5165.5 5174.4 25500.5 25738.5 26159.9 26312.5 26435.3 2412.5 2601.3 3090.5 3146.8 3205.1 14781.9 14785.6 15215.5 15513.8 15516.5 1856.6 1898.6 1908.3 1927.5 2017.8 111888.0 117569.4 117785.0 117962.0 118997.8 10710.1 10859.8 11131.5 11145.6 11284.3 13735.7 13868.9 13868.9 13894.9 13904.9 8490.4 8667.8 8695.1 8804.0 8809.5 7191.9 8709.7 8713.9 8722.7 8823.0 6639.0 6711.7 6746.7 6762.7 6769.2 1843.8 1868.5 1871.7 1880.2 1897.5 921.5 1016.3 1018.8 1049.8 1049.9 18668.8 20289.9 21072.6 21758.7 21834.7 987.5 998.1 1000.7 1007.8 1089.2 11166.9 11631.8 11740.8 11755.8 11757.5 580658.0 588912.0 601436.0 602199.0 611237.9 87966.6 116697.0 119613.0 132266.0 136761.9 989.7 998.5 999.3 1002.0 1002.1 18649.7 18830.5 19114.3 19123.3 19129.3 39083.8 56009.4 56035.6 56140.7 56754.7 74749.0 77209.6 79437.6 82199.2 84466.6 7098.7 8010.6 8097.7 8270.7 8291.3 10452.0 12589.3 12935.5 13104.5 13199.1 11004.1 11084.6 11114.4 11152.8 11301.9 10883.6 11009.7 11041.4 11050.4 11205.7 11326.9 11345.8 11439.9 11482.2 11746.2 6347.4 8114.3 8143.3 8162.4 8348.2 8227.7 10223.0 11231.9 11308.9 11340.4 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | [#3] mootools.html Cycles:180 Average:5704.85 Median:5116.24 stddev:3190.57 (62.4%) stddev-sans-first:3183.03 08:17:24 INFO - PROCESS | 1953 | Values: 1388.4 1425.0 1471.8 1514.0 1972.3 7789.4 8531.1 8734.3 8744.5 8820.5 8412.4 8669.1 8676.2 8700.7 8702.2 1843.4 2103.1 2178.6 2325.2 2361.9 2179.9 2204.9 2333.2 2413.9 2417.0 2373.1 2376.1 2377.8 2379.6 2385.9 2324.1 2377.4 2377.5 2380.8 2382.7 1969.8 2097.5 2340.2 2379.8 2401.1 8646.2 8730.8 8731.7 8747.2 8747.7 5199.2 5377.0 5379.3 5386.1 5409.5 3508.9 3617.5 3670.9 3681.9 3684.1 1065.3 1096.7 1103.4 1106.0 1118.4 11701.0 11732.8 11742.2 11748.7 11750.6 4607.6 4762.7 4817.1 4846.7 4858.5 9952.4 9959.0 9959.5 9963.8 9965.0 5365.1 5372.3 5373.3 5382.1 5402.2 4469.6 4769.6 4850.1 4943.0 4944.5 3621.9 3791.7 3809.0 3870.0 3901.2 1167.7 1168.9 1175.3 1180.0 1180.8 792.4 824.7 880.8 886.7 985.0 5883.9 5973.0 5994.0 6077.8 6198.3 917.8 968.9 998.5 999.6 1038.1 3924.2 4044.2 4079.5 4112.8 4114.4 8633.0 10512.6 10695.3 10702.3 10704.1 10599.7 10720.6 10732.5 10747.4 10754.9 7551.4 8075.6 8250.5 8329.8 8350.2 5852.7 5875.6 5932.3 5965.5 5990.6 10002.8 10182.5 10189.9 10201.8 10202.4 8919.6 9827.0 9981.5 9999.4 10275.6 7069.6 7279.3 7511.9 7709.2 7728.3 8129.7 8392.5 8404.7 8505.0 8537.4 7292.6 7461.6 7499.6 7621.0 7632.5 7159.2 7365.2 7393.3 7436.4 7436.7 7033.3 7083.5 7127.4 7136.7 7146.6 4482.4 4903.7 4963.6 5105.7 5148.6 5067.2 5126.8 5141.9 5164.0 5272.9 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | [#4] prototype.html Cycles:180 Average:4043.66 Median:4379.42 stddev:1238.94 (28.3%) stddev-sans-first:1209.31 08:17:24 INFO - PROCESS | 1953 | Values: 253.4 297.0 309.2 314.8 315.4 4946.3 5129.5 5147.8 5158.3 5298.5 4933.9 5063.3 5141.6 5166.2 5168.5 3351.5 3902.9 3917.6 3954.6 3966.3 3231.5 3526.9 3935.8 3995.9 3999.8 3281.7 3316.5 3944.5 3947.2 3948.6 3888.6 3892.4 3911.8 3917.0 3966.3 3069.8 3419.0 3423.5 3444.9 3451.9 4801.9 4913.4 4918.8 4937.3 5023.7 4464.6 4480.8 4484.7 4486.9 4493.9 4388.5 4422.2 4714.9 4955.4 5331.5 3216.7 3619.6 3785.8 3791.4 3801.0 5372.4 5606.9 5611.8 5662.4 5733.3 3409.4 3845.6 3864.3 3914.4 3945.6 3044.8 3426.5 3429.8 3650.4 3780.0 3555.9 4475.0 4484.6 4487.2 4496.8 5346.4 5383.4 5437.8 5440.8 5515.8 1210.9 1235.6 1247.8 1292.3 1301.2 744.4 758.6 763.9 788.3 823.6 3682.5 3955.0 4081.1 4151.0 4155.6 3976.4 4072.7 4126.1 4351.9 4686.9 3360.6 3661.6 3979.9 4021.3 4082.4 2114.6 2154.8 2267.8 2271.3 2430.2 4491.4 4537.4 4858.6 4873.9 4886.2 4160.5 4673.4 4722.2 4793.3 4841.9 4024.6 4294.8 4361.4 4370.3 4506.9 3848.5 4097.2 4136.2 4530.8 4562.5 4021.9 4105.0 4480.6 4576.8 4669.2 4100.1 4291.7 4694.5 4720.7 4846.6 2764.2 2943.0 3185.9 3320.9 3349.7 4299.4 4599.7 4677.3 4677.4 4684.5 4195.6 4545.5 5328.7 5413.1 5482.7 4362.3 4865.3 4890.6 5261.9 5293.9 4907.3 4964.9 5022.2 5254.4 5278.7 5340.6 5356.6 5364.7 5372.7 5409.3 4096.0 4399.7 5264.7 5312.3 5339.0 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - PROCESS | 1953 | [#5] yui.html Cycles:180 Average:22575.92 Median:24837.35 stddev:33691.79 (135.6%) stddev-sans-first:33746.28 08:17:24 INFO - PROCESS | 1953 | Values: 705.3 714.9 766.9 785.5 794.3 15421.7 15459.0 15625.4 15770.1 15790.5 14478.3 14610.6 14612.1 14620.6 14628.5 902.5 911.6 921.3 922.7 923.6 718.9 721.4 746.2 915.1 920.4 960.0 960.9 963.7 963.8 965.1 743.7 951.0 954.0 954.0 955.5 699.5 714.7 715.9 719.5 721.4 15114.8 15144.8 15156.5 15190.9 15194.5 13798.2 13986.0 14066.0 14094.4 14304.1 12809.3 12859.2 12911.7 13061.3 13380.2 4575.7 4580.4 4615.3 4682.7 4784.3 104076.0 105681.0 107534.0 107707.5 109123.4 11101.7 11110.7 11274.5 11283.2 11484.0 22304.0 22325.4 22327.0 22358.9 22381.8 7870.1 8014.3 8064.6 8082.2 8094.4 4491.8 4496.6 4526.9 4578.8 4654.2 3480.6 3596.6 3603.8 3608.0 3616.7 593.9 612.9 639.8 639.8 654.7 537.4 559.3 632.2 635.3 644.0 8897.6 9249.7 9295.3 9329.2 9445.4 532.9 534.4 570.6 622.8 656.2 3730.3 4307.6 4405.8 4538.0 4562.5 89259.7 115632.4 116452.0 121368.4 126434.0 105315.1 108717.5 110763.4 111339.0 113099.4 90865.0 93094.0 93126.0 93832.5 94412.0 9587.4 9817.8 9821.7 9823.2 9841.0 45178.1 53193.7 56560.4 58166.7 58912.2 83361.3 86549.7 86861.6 87781.0 89023.0 12809.2 13259.5 13494.4 13640.1 13834.2 14349.6 14458.9 14503.6 14694.6 15434.4 13494.2 13694.0 13707.2 13714.4 13859.3 13865.4 14026.4 14250.9 14316.3 14328.6 13266.5 13364.7 13518.2 13525.6 14098.1 11152.6 11518.8 11530.8 11568.1 11620.8 9286.9 9569.2 9625.6 10192.1 11582.6 08:17:24 INFO - PROCESS | 1953 | -------- Summary: end -------- 08:17:24 INFO - PROCESS | 1953 | 08:17:24 INFO - TEST-INFO | 1953: exit 0 08:17:24 INFO - TEST-OK | dromaeo_css | took 1117489ms 08:17:24 INFO - TEST-START | kraken 08:17:24 INFO - operating with platform_type : mac_ 08:17:24 INFO - Initialising browser for kraken test... 08:17:24 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpgkdNPz/profile http://localhost:50333/getInfo.html) 08:17:26 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 08:17:26 INFO - PROCESS | 1956 | colorDepth:24 08:17:26 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 08:17:26 INFO - PROCESS | 1956 | __metrics 08:17:27 INFO - PROCESS | 1956 | 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:17:27 ERROR - PROCESS | 1956 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:17:27 INFO - TEST-INFO | 1956: exit 0 08:17:27 INFO - Browser initialized. 08:17:27 INFO - Running cycle 1/1 for kraken test... 08:17:27 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpgkdNPz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:39 INFO - PROCESS | 1957 | RSS: Main: 242253824 08:17:39 INFO - PROCESS | 1957 | 08:17:42 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50333/tests/kraken/driver.html?testName=audio-beat-detection) 08:17:43 INFO - PROCESS | 1957 | RSS: Main: 291852288 08:17:43 INFO - PROCESS | 1957 | 08:17:46 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50333/tests/kraken/driver.html?testName=audio-dft) 08:17:46 INFO - PROCESS | 1957 | RSS: Main: 302043136 08:17:46 INFO - PROCESS | 1957 | 08:17:50 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50333/tests/kraken/driver.html?testName=audio-fft) 08:17:51 INFO - PROCESS | 1957 | RSS: Main: 388415488 08:17:51 INFO - PROCESS | 1957 | 08:17:52 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50333/tests/kraken/driver.html?testName=audio-oscillator) 08:17:53 INFO - PROCESS | 1957 | RSS: Main: 287346688 08:17:53 INFO - PROCESS | 1957 | 08:17:55 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50333/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:17:56 INFO - PROCESS | 1957 | RSS: Main: 291250176 08:17:56 INFO - PROCESS | 1957 | 08:17:59 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50333/tests/kraken/driver.html?testName=imaging-darkroom) 08:17:59 INFO - PROCESS | 1957 | RSS: Main: 328155136 08:17:59 INFO - PROCESS | 1957 | 08:18:02 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50333/tests/kraken/driver.html?testName=imaging-desaturate) 08:18:03 INFO - PROCESS | 1957 | RSS: Main: 331190272 08:18:03 INFO - PROCESS | 1957 | 08:18:06 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50333/tests/kraken/driver.html?testName=json-parse-financial) 08:18:06 INFO - PROCESS | 1957 | RSS: Main: 327557120 08:18:06 INFO - PROCESS | 1957 | 08:18:08 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50333/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:18:08 INFO - PROCESS | 1957 | RSS: Main: 309952512 08:18:08 INFO - PROCESS | 1957 | 08:18:10 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:18:11 INFO - PROCESS | 1957 | RSS: Main: 316964864 08:18:11 INFO - PROCESS | 1957 | 08:18:13 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:18:14 INFO - PROCESS | 1957 | RSS: Main: 321417216 08:18:14 INFO - PROCESS | 1957 | 08:18:17 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:18:17 INFO - PROCESS | 1957 | RSS: Main: 323993600 08:18:17 INFO - PROCESS | 1957 | 08:18:20 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:18:21 INFO - PROCESS | 1957 | RSS: Main: 323702784 08:18:21 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:50333/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50333/tests/kraken/driver.html?testName=ai-astar) 08:18:23 INFO - PROCESS | 1957 | RSS: Main: 367398912 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | __start_tp_report 08:18:23 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 08:18:23 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 08:18:23 INFO - PROCESS | 1957 | |i|pagename|runs| 08:18:23 INFO - PROCESS | 1957 | |0;ai-astar;156;150;154;113;114;111;112;114;111;113 08:18:23 INFO - PROCESS | 1957 | |1;audio-beat-detection;155;123;127;198;141;124;145;136;179;115 08:18:23 INFO - PROCESS | 1957 | |2;audio-dft;185;188;185;183;183;185;183;184;182;183 08:18:23 INFO - PROCESS | 1957 | |3;audio-fft;78;74;58;107;59;56;65;64;96;57 08:18:23 INFO - PROCESS | 1957 | |4;audio-oscillator;87;90;92;72;93;99;109;70;67;101 08:18:23 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;90;88;92;89;91;87;91;88;90;95 08:18:23 INFO - PROCESS | 1957 | |6;imaging-darkroom;95;96;96;96;95;96;95;94;95;95 08:18:23 INFO - PROCESS | 1957 | |7;imaging-desaturate;87;83;84;84;87;83;85;85;85;86 08:18:23 INFO - PROCESS | 1957 | |8;json-parse-financial;56;56;55;57;56;54;54;55;54;55 08:18:23 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;66;66;74;89;98;66;64;81;94;103 08:18:23 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;87;85;87;86;85;82;87;88;143;87 08:18:23 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;139;141;136;143;140;150;144;144;153;139 08:18:23 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;169;169;169;168;174;210;175;169;165;168 08:18:23 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;67;66;67;67;66;68;66;66;69;74 08:18:23 INFO - PROCESS | 1957 | __end_tp_report 08:18:23 INFO - PROCESS | 1957 | __start_cc_report 08:18:23 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,1965 08:18:23 INFO - PROCESS | 1957 | __end_cc_report 08:18:23 INFO - PROCESS | 1957 | __startTimestamp1464880703370__endTimestamp 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | ------- Summary: start ------- 08:18:23 INFO - PROCESS | 1957 | Number of tests: 14 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:124.80 Median:114.00 stddev:19.77 (17.3%) stddev-sans-first:17.45 08:18:23 INFO - PROCESS | 1957 | Values: 156.0 150.0 154.0 113.0 114.0 111.0 112.0 114.0 111.0 113.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:143.00 stddev:26.47 (18.5%) stddev-sans-first:27.79 08:18:23 INFO - PROCESS | 1957 | Values: 155.0 123.0 127.0 198.0 141.0 124.0 145.0 136.0 179.0 115.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.73 (0.9%) stddev-sans-first:1.80 08:18:23 INFO - PROCESS | 1957 | Values: 185.0 188.0 185.0 183.0 183.0 185.0 183.0 184.0 182.0 183.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:71.40 Median:64.50 stddev:17.63 (27.3%) stddev-sans-first:18.53 08:18:23 INFO - PROCESS | 1957 | Values: 78.0 74.0 58.0 107.0 59.0 56.0 65.0 64.0 96.0 57.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:88.50 stddev:14.13 (16.0%) stddev-sans-first:14.99 08:18:23 INFO - PROCESS | 1957 | Values: 87.0 90.0 92.0 72.0 93.0 99.0 109.0 70.0 67.0 101.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:90.50 stddev:2.33 (2.6%) stddev-sans-first:2.47 08:18:23 INFO - PROCESS | 1957 | Values: 90.0 88.0 92.0 89.0 91.0 87.0 91.0 88.0 90.0 95.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:18:23 INFO - PROCESS | 1957 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 95.0 94.0 95.0 95.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.32 08:18:23 INFO - PROCESS | 1957 | Values: 87.0 83.0 84.0 84.0 87.0 83.0 85.0 85.0 85.0 86.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.03 (1.9%) stddev-sans-first:1.05 08:18:23 INFO - PROCESS | 1957 | Values: 56.0 56.0 55.0 57.0 56.0 54.0 54.0 55.0 54.0 55.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:80.10 Median:77.50 stddev:14.95 (19.3%) stddev-sans-first:14.96 08:18:23 INFO - PROCESS | 1957 | Values: 66.0 66.0 74.0 89.0 98.0 66.0 64.0 81.0 94.0 103.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:87.00 stddev:18.10 (20.8%) stddev-sans-first:19.12 08:18:23 INFO - PROCESS | 1957 | Values: 87.0 85.0 87.0 86.0 85.0 82.0 87.0 88.0 143.0 87.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:143.50 stddev:5.22 (3.6%) stddev-sans-first:5.34 08:18:23 INFO - PROCESS | 1957 | Values: 139.0 141.0 136.0 143.0 140.0 150.0 144.0 144.0 153.0 139.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:169.00 stddev:13.12 (7.8%) stddev-sans-first:13.81 08:18:23 INFO - PROCESS | 1957 | Values: 169.0 169.0 169.0 168.0 174.0 210.0 175.0 169.0 165.0 168.0 08:18:23 INFO - PROCESS | 1957 | 08:18:23 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:2.46 (3.7%) stddev-sans-first:2.60 08:18:23 INFO - PROCESS | 1957 | Values: 67.0 66.0 67.0 67.0 66.0 68.0 66.0 66.0 69.0 74.0 08:18:23 INFO - PROCESS | 1957 | -------- Summary: end -------- 08:18:23 INFO - PROCESS | 1957 | 08:18:24 INFO - TEST-INFO | 1957: exit 0 08:18:24 INFO - TEST-OK | kraken | took 59641ms 08:18:24 INFO - SUITE-END | took 1177s 08:18:24 INFO - Completed test suite (00:19:37) 08:18:24 INFO - javascript benchmark 08:18:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3399.269157131216, 3407.1652445150944, 3435.8453869575865, 3453.9136521586943, 3474.722022238221, 5474.863128421792, 5565.6660600364, 5566.053770858954, 5633.408492108326, 5671.2060311556315, 5586.24585680933, 5639.68981706006, 5641.210230567724, 5643.830685079433, 5655.660660360381, 5089.770960306777, 5114.360704911886, 5126.410462796789, 5144.6655967362185, 5204.089284375238, 4155.376693495969, 5205.869853253661, 5208.270842082112, 5211.7133557654315, 5217.504337087989, 5038.420581633122, 5091.516305950929, 5120.641555091145, 5137.948620513803, 5143.588512918957, 4297.205017071852, 5210.817621383271, 5222.582193424517, 5223.164293712987, 5272.261883336336, 5046.899062018776, 5048.242763585455, 5113.923291150636, 5139.203423469368, 5143.819966301162, 5274.2879711238875, 5302.787888484456, 5457.317835270591, 5555.333359996826, 5586.35756887959, 5798.710064496759, 5830.737616807254, 5853.88292234153, 5904.321003084658, 5904.940950590525, 5947.583669143161, 5951.821445356646, 5973.492253158487, 5998.40015998401, 6126.540509461764, 5684.488396044376, 5760.251167348218, 5772.826815195551, 5797.942020579824, 5805.419458054161, 6231.532635052389, 6235.220597425322, 6256.968715156486, 6264.060390941395, 6281.780137695158, 5907.9704601476715, 5977.073553599199, 6108.461860771425, 6109.0, 6110.564017455894, 6157.983932651152, 6210.130581718563, 6210.968945155245, 6226.0, 6231.096491008869, 6141.662208578571, 6154.169187159766, 6156.168917196122, 6162.137300777895, 6162.938370616236, 5940.732667029994, 6086.786962456293, 6108.969455152695, 6145.631262124287, 6165.290991535941, 4861.854144375604, 4871.0, 4887.3890763654545, 4887.877803054952, 4893.119238537098, 4372.278574035312, 4379.715318504287, 4401.317795741666, 4429.402030725875, 4455.732656040648, 5655.915161272585, 5664.178694089334, 5714.0571805652435, 5719.7712091516705, 5720.256366672306, 6203.8138855834395, 6220.284667263322, 6224.906626400608, 6230.59501132425, 6232.594881332699, 5546.611737178439, 5649.0, 5652.0, 5657.717114144276, 5663.9716801415725, 5283.07546179417, 5326.0, 5355.919661205085, 5358.758855851496, 5359.981603495401, 6186.412290832363, 6262.405071518198, 6267.1539342188225, 6268.843278918063, 6325.936740632626, 6211.81364559064, 6242.28213755424, 6248.031555108965, 6255.46828519581, 6266.122742815918, 6203.937960620517, 6256.530760192913, 6256.749730010749, 6258.998560230341, 6261.279952805304, 6098.512119030555, 6127.724252408563, 6130.570860039755, 6131.202943617302, 6132.35610260934, 6104.06507294887, 6169.1054797053275, 6201.317855036033, 6239.968800156152, 6253.5935164216, 5968.31364393083, 6058.0610005449225, 6063.9696801517475, 6074.37914009963, 6078.392160784063, 5796.188533605383, 6032.788852390146, 6044.455998960115, 6047.78832740852, 6065.7876974307455, 4845.0, 5720.19917211599, 5772.24960755116, 5789.073748200268, 5798.420157984337, 5781.190633311424, 5875.0, 5915.053591425523, 5921.2006379138265, 5952.821415357546, 5798.59409841307, 5817.0, 5883.558733094949, 5894.322152952465, 5979.491743201703], "value": 5611.715733625044, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.96136309095255, 483.3909274314363, 487.3566891702949, 520.3599572525793, 542.4006472847503, 24520.509589808193, 31788.841055794717, 31789.887353942588, 41957.7902110489, 42684.71945841622, 44491.11017779642, 44654.0, 44955.550444495544, 44994.55005449944, 45048.77475612617, 9402.67090651829, 9653.662121825737, 9664.661736839213, 9686.225101991859, 9701.902980970206, 8574.657013719443, 8797.692080777175, 9316.953415232949, 9842.769653793275, 11102.222844400896, 11643.767124657501, 11740.29558226505, 11744.647660570197, 11749.647510574696, 11752.118591105658, 8570.871436928452, 11744.41277936104, 11853.762924741457, 11870.940645296718, 11890.464929078213, 11287.040601548879, 11683.41582920859, 11700.765984680258, 11909.523619055228, 11938.462769175321, 40803.0, 41234.793826030975, 41285.0, 41409.79295103535, 41562.0, 3545.3888515112953, 3592.8742494012586, 3603.531540899666, 3609.0, 3662.8351724172207, 26529.336766580796, 31386.0, 31922.6807731922, 32627.836860815543, 32657.0, 3269.5749552558254, 3360.5295258663805, 3374.105861946586, 3390.762646614738, 3399.711024562915, 509408.0, 509422.0, 509699.0, 509943.9999999963, 510169.0, 61749.0, 62179.378206218265, 62281.688591556755, 62287.39413452811, 62321.68839155775, 65237.0, 65341.0, 65423.345766542676, 65470.34529654738, 65529.672351637935, 14941.70116597662, 15841.970271932518, 16034.75947860783, 16063.277152528164, 16104.0, 11673.824892626617, 12081.395930203455, 12091.972182364598, 12095.274283543016, 12755.936220319025, 16438.671226575403, 16869.493915182564, 16938.49184524466, 16977.40579079751, 16989.575260618338, 6469.644169570624, 6915.031895534629, 6997.580145191305, 7053.894191587027, 7066.611336376446, 803.7387848948998, 1005.0753306957616, 1070.822095694745, 1113.8997490225756, 1192.1538944191832, 24313.75686243115, 28181.859090704416, 31694.11928235976, 32366.676333236835, 32960.01119966405, 1012.2964539645023, 1115.9944200279108, 1136.8863113688565, 1139.2594813371375, 1166.393475392791, 15128.716822989467, 15635.26382882991, 15639.3744250231, 15684.137372444397, 16025.358985640676, 660243.0, 665818.0, 672973.0, 673467.0, 673640.0, 57985.71007144937, 58275.0, 58362.708186458796, 58437.0, 58560.4143958555, 1255.2280347586168, 1315.5658632651393, 1386.4662105089412, 1394.5119208277224, 1411.7529432349297, 28377.432451350443, 30024.849875750482, 32092.588355830616, 32712.0, 33204.0, 58338.83322333614, 58557.70721146367, 59390.0, 59409.0, 59533.40466595452, 571383.0, 577182.0, 578771.0, 579850.0, 581530.0, 23805.285841424786, 26167.345816354744, 26470.735292646827, 26599.0, 26995.59506607363, 37299.0, 40806.0, 41337.379939301536, 44582.777086114365, 50580.747096264284, 40128.0, 40911.59088409078, 41558.273860075, 41975.58024419839, 42660.57339426566, 40060.399094014196, 40289.99275018148, 40367.659540475455, 40424.0, 41344.96637584084, 31464.68535314709, 38832.223355532165, 39144.82565523039, 39248.0, 39289.607103928596, 34002.97991060272, 34355.82822085873, 34412.48381274233, 34937.65062349445, 35017.299654007285, 25545.872270638527, 25798.742012580384, 25800.61299080553, 26298.795421602284, 26442.206733798015], "value": 20444.480313642027, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.6636444018161, 383.7946698516294, 384.61922696530434, 389.6882494004797, 412.7420362273579, 18747.0, 21900.342989710334, 21984.01071951762, 22138.889305553468, 22169.334919952387, 20205.898970505164, 20464.795352046476, 21045.894770526163, 21168.4707882303, 21314.57370852582, 5153.665011774223, 5770.249867517227, 5889.911651325234, 5991.891500072479, 6002.969985150068, 5851.970740146293, 5907.822765317026, 5920.230370051887, 5942.316633587128, 5952.255968003999, 5679.091345384739, 5836.2121113649755, 5839.941600583982, 5846.093855452412, 5872.207252020987, 5529.502344788948, 5843.298804143508, 5851.15158302046, 5851.941480585182, 5886.823395298148, 5131.486851314876, 5153.175491921274, 5163.277141200235, 5165.457626949192, 5174.404943431516, 25500.48999020009, 25738.485230295475, 26159.869200654066, 26312.47375052488, 26435.33911652205, 2412.541617092747, 2601.297649634588, 3090.505519116953, 3146.763992700534, 3205.0705295464286, 14781.926090369587, 14785.630359240997, 15215.467458639005, 15513.844861551466, 15516.456924007718, 1856.6163688199606, 1898.5538398476347, 1908.3225454963565, 1927.5084853362303, 2017.8486613503899, 111888.0, 117569.41215293869, 117785.0, 117962.0, 118997.8100219004, 10710.089642380484, 10859.83710244347, 11131.499082541304, 11145.609903653332, 11284.266522676, 13735.656608584864, 13868.86131138696, 13868.86131138696, 13894.861051389558, 13904.930475347559, 8490.363222758318, 8667.826643467095, 8695.13048695138, 8804.031556528776, 8809.515476648718, 7191.89212161818, 8709.651613935372, 8713.869291960626, 8722.738317850475, 8823.0, 6638.96680516604, 6711.664416779142, 6746.730130794714, 6762.72949082041, 6769.172323472635, 1843.824836640517, 1868.4768264885854, 1871.6537440573265, 1880.2385034061226, 1897.5066482714594, 921.5392303848076, 1016.2835201183149, 1018.8369860822232, 1049.8267785815256, 1049.8635177427038, 18668.78652887585, 20289.898550507154, 21072.578548428944, 21758.673619895715, 21834.672479912813, 987.5062468765617, 998.1116806042628, 1000.7448100734412, 1007.7934023525306, 1089.2429761315968, 11166.944165279121, 11631.76736465266, 11740.765184696258, 11755.764884702257, 11757.529698812123, 580658.0, 588912.0, 601436.0, 602199.0, 611237.887621127, 87966.56016719875, 116697.0, 119613.0, 132266.0, 136761.94857077152, 989.7179303898498, 998.5406712911996, 999.3304485994402, 1001.9549120289604, 1002.0530598584413, 18649.720254195927, 18830.52923676864, 19114.33099841499, 19123.330683426015, 19129.33047343336, 39083.80458097691, 56009.439905600426, 56035.59911002257, 56140.71929640489, 56754.716226420256, 74749.0, 77209.61395192987, 79437.60281198558, 82199.17800821915, 84466.57766711127, 7098.654886978702, 8010.556393364451, 8097.663885458834, 8270.669173233244, 8291.295239904675, 10452.0, 12589.307588082744, 12935.547255846377, 13104.475820967054, 13199.142055766344, 11004.119670426508, 11084.61203857861, 11114.44427778624, 11152.776944461228, 11301.943490282496, 10883.564657413615, 11009.669709908716, 11041.447927603427, 11050.392228427514, 11205.66383008511, 11326.886731132583, 11345.773084538097, 11439.885601143882, 11482.196246262682, 11746.236494627821, 6347.39699728525, 8114.269715725613, 8143.267105960492, 8162.387820913574, 8348.165183481846, 8227.670893164206, 10223.0, 11231.887681123084, 11308.886911130783, 11340.376279304717], "value": 9856.425165632667, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.3544151969331, 1424.9715005699886, 1471.8233811942569, 1513.969720605588, 1972.3294080012784, 7789.376849852012, 8531.080506885948, 8734.257588105003, 8744.519051452176, 8820.470771753684, 8412.369072319585, 8669.089745576719, 8676.175763302475, 8700.738977830675, 8702.173293537104, 1843.4008947092182, 2103.095668862396, 2178.56428714257, 2325.2210509479346, 2361.8937147828387, 2179.8583092099043, 2204.8677079375207, 2333.218371845434, 2413.903443862252, 2416.9516609667794, 2373.074500944633, 2376.061455724988, 2377.8454400463916, 2379.5597814404305, 2385.8926348314367, 2324.0936034946385, 2377.4413012941945, 2377.548265829487, 2380.797632201248, 2382.7140743110763, 1969.7885800232898, 2097.4861159015986, 2340.2277248507953, 2379.845310054841, 2401.135591187171, 8646.178613031752, 8730.825383492294, 8731.650733970633, 8747.21275085239, 8747.737567872973, 5199.220116982445, 5377.0321342158395, 5379.300690910196, 5386.13821788512, 5409.486098820645, 3508.9298214035575, 3617.5116359291424, 3670.889873303805, 3681.852725890961, 3684.0973961379364, 1065.2702898514465, 1096.6874440784418, 1103.4151899493281, 1106.0156460749859, 1118.429600903537, 11701.00541453969, 11732.765344693144, 11742.236754611009, 11748.706282342924, 11750.58872939443, 4607.562281583244, 4762.690425122356, 4817.084753896748, 4846.684965477232, 4858.5384388483035, 9952.402855828674, 9959.0, 9959.452230127412, 9963.750906227257, 9965.003499649976, 5365.1147560652835, 5372.274742909735, 5373.301470808848, 5382.058139825514, 5402.18967154922, 4469.642428605704, 4769.642276829251, 4850.102730994778, 4942.950570494321, 4944.456109827916, 3621.8732344367822, 3791.696664266852, 3809.0, 3869.955112119712, 3901.180752042046, 1167.6730515455686, 1168.9064874809994, 1175.3241885915634, 1179.9646010619695, 1180.7933611617932, 792.4334101117613, 824.7113510271357, 880.7665968518347, 886.7054101012594, 984.9704508864745, 5883.882322353529, 5973.0, 5994.0, 6077.817665470043, 6198.318184999646, 917.7698193390127, 968.8837339519303, 998.4508520313798, 999.5801763259449, 1038.076112260089, 3924.234774219, 4044.1507283470214, 4079.5104587449696, 4112.773797441169, 4114.382842573638, 8632.956835215784, 10512.632057877938, 10695.304805187638, 10702.25084244111, 10704.1436685065, 10599.682009539727, 10720.624778132727, 10732.463376831127, 10747.408892510986, 10754.946225268823, 7551.433642476836, 8075.596220188967, 8250.54621995796, 8329.791755206046, 8350.164983501601, 5852.707364631837, 5875.588708790515, 5932.288125424977, 5965.492933100735, 5990.580659353804, 10002.849957250794, 10182.49087545605, 10189.949050254998, 10201.846972295272, 10202.38785672862, 8919.643214271357, 9826.96816834222, 9981.500924953869, 9999.400035997864, 10275.640352587623, 7069.646517674199, 7279.27207279289, 7511.924880751122, 7709.151993280622, 7728.26581474759, 8129.715459958873, 8392.538410387488, 8404.747857564284, 8504.979402471743, 8537.359698022798, 7292.598907060163, 7461.626918653937, 7499.62501874915, 7620.961895190489, 7632.465727399029, 7159.176694680178, 7365.226651201761, 7393.334599886036, 7436.442266829901, 7436.665350059368, 7033.296670332926, 7083.504154709327, 7127.429805615642, 7136.7145314186855, 7146.571205727673, 4482.350059241327, 4903.686441728353, 4963.553280204799, 5105.693658380361, 5148.639595228298, 5067.214581739836, 5126.7949282029795, 5141.871453213699, 5164.04465173929, 5272.920906186487], "value": 4574.8923585396515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.3665835411471, 296.95397213431926, 309.15600410878295, 314.762354422411, 315.4054607065681, 4946.282788995593, 5129.53834154926, 5147.7645365112385, 5158.303629010083, 5298.483220470274, 4933.939203071339, 5063.265826455162, 5141.614378921587, 5166.225066240066, 5168.48315168483, 3351.497275408684, 3902.8633997810084, 3917.6102577427746, 3954.644082032624, 3966.266240745457, 3231.515272709089, 3526.8589256429714, 3935.783531905744, 3995.9200815983663, 3999.7600743769526, 3281.671832816723, 3316.535685004101, 3944.4872166618443, 3947.1710940702487, 3948.644621984021, 3888.6111388861173, 3892.377219644857, 3911.784851833148, 3916.980415097935, 3966.3058964681354, 3069.8311592862383, 3419.0426680529245, 3423.4864770284407, 3444.9311013779584, 3451.9299017304465, 4801.871560183354, 4913.361263035782, 4918.754062296906, 4937.333839726543, 5023.748812559357, 4464.598186163228, 4480.843170489017, 4484.663650226214, 4486.932696009562, 4493.932591011137, 4388.539203383631, 4422.181896349186, 4714.905701885943, 4955.36472963921, 5331.5201631853315, 3216.726578240852, 3619.60184379718, 3785.7728536287636, 3791.393377059685, 3800.9737370909975, 5372.409035006146, 5606.887862242773, 5611.8316450506145, 5662.405447428002, 5733.283339582552, 3409.4316614357176, 3845.576986531479, 3864.265789499986, 3914.3541315683165, 3945.6054394560315, 3044.802087864282, 3426.52028715975, 3429.8456569453933, 3650.415933450688, 3779.962200378016, 3555.8577656893954, 4474.986256184665, 4484.573965473275, 4487.237169681162, 4496.842610508616, 5346.385165705994, 5383.353997520323, 5437.8368648940595, 5440.809571664972, 5515.834524964257, 1210.9273443593413, 1235.6416639174718, 1247.7941139711845, 1292.3473645808806, 1301.1932601786953, 744.3858816476428, 758.5714071549556, 763.9465237433326, 788.3102285500187, 823.5546615689427, 3682.5028621135802, 3954.9604503954592, 4081.081891510985, 4151.045259590311, 4155.625993660585, 3976.4035394691027, 4072.6741860651077, 4126.051008268115, 4351.891202719958, 4686.929696054562, 3360.572216339369, 3661.6085887362633, 3979.905525980321, 4021.2761702893176, 4082.448669505569, 2114.6149272226717, 2154.849160558746, 2267.7505474397803, 2271.273192578359, 2430.2223288547493, 4491.416115904912, 4537.413674205671, 4858.58702010326, 4873.9025219496125, 4886.193778026586, 4160.500739911169, 4673.3690951721765, 4722.197226471508, 4793.304970779218, 4841.869577385939, 4024.6176613221696, 4294.828206871753, 4361.433013708198, 4370.300751879684, 4506.864794056183, 3848.499695039627, 4097.242010228118, 4136.187392573079, 4530.773461326987, 4562.498125206291, 4021.9086075241294, 4105.023857703141, 4480.572362143029, 4576.780261573068, 4669.221813021247, 4100.052475074316, 4291.721038132636, 4694.474295853929, 4720.749001514533, 4846.5638092571835, 2764.156932135718, 2942.985285073561, 3185.888493902702, 3320.9169770755925, 3349.6650334966307, 4299.355096735515, 4599.653327605381, 4677.3451716759, 4677.438707355139, 4684.531546845425, 4195.622393984556, 4545.454545454567, 5328.653637513549, 5413.079776438094, 5482.725863706878, 4362.2987035056985, 4865.343178670834, 4890.628045397908, 5261.89476210466, 5293.914747476681, 4907.312976183409, 4964.882901347168, 5022.15804909252, 5254.42201357858, 5278.683279003272, 5340.635399698165, 5356.5982551308025, 5364.731763411892, 5372.731363431891, 5409.323834520685, 4096.037431203606, 4399.658104278225, 5264.736763161904, 5312.309399778004, 5338.97330513345], "value": 3657.7499807370214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.3334598804131, 714.8856183010716, 766.8811334243194, 785.5365334452672, 794.3089512124452, 15421.691566168683, 15458.995165314262, 15625.374985000615, 15770.053796772187, 15790.526284211463, 14478.348474318655, 14610.561683149523, 14612.050216735905, 14620.561383158521, 14628.4880029199, 902.503623007345, 911.5578944212052, 921.3320342751485, 922.6862866625346, 923.6490133749165, 718.8526352097807, 721.3507842941368, 746.2462912458402, 915.0803442540238, 920.4201353147541, 960.0159836167913, 960.8894977077621, 963.6964356227766, 963.8457846744523, 965.0542468380991, 743.6542007966268, 950.9762255943588, 953.9904600953971, 954.0364232125534, 955.5270141280076, 699.4753934549088, 714.7033980897921, 715.9033530473372, 719.4963525532096, 721.3651986252117, 15114.848851511455, 15144.772828407582, 15156.545303640798, 15190.924045379812, 15194.544163675107, 13798.241096739775, 13986.020978531507, 14066.0, 14094.436222551085, 14304.141751494839, 12809.295488748116, 12859.16415432994, 12911.67720806978, 13061.346932653329, 13380.19718816874, 4575.700115960178, 4580.358749775034, 4615.330777037344, 4682.695624784412, 4784.306275590055, 104076.0, 105681.0, 107534.0, 107707.46146269297, 109123.36314955202, 11101.72245693848, 11110.723383466408, 11274.492647830868, 11283.210175287815, 11484.023857971999, 22304.0, 22325.441863953532, 22327.0, 22358.88820555887, 22381.77618223797, 7870.055593328837, 8014.318782903519, 8064.596770161468, 8082.19178082187, 8094.352451803841, 4491.842785502492, 4496.595306422372, 4526.864194074183, 4578.771061446914, 4654.208784506574, 3480.564929383827, 3596.6403359663823, 3603.801790901473, 3607.981960090235, 3616.6564176403726, 593.9406059394113, 612.9295131059896, 639.7888696730068, 639.8336432527483, 654.6628486329544, 537.4410612962554, 559.2506041903869, 632.2476253258608, 635.3138610300864, 643.9710213040424, 8897.599608017656, 9249.676261330822, 9295.349325547148, 9329.210109558458, 9445.433274003582, 532.9307190065344, 534.4094775273345, 570.6034306157181, 622.8011078673626, 656.2256537285953, 3730.3285408626707, 4307.590778876001, 4405.7797110144365, 4538.0, 4562.475315338778, 89259.66110508348, 115632.42183789027, 116452.0, 121368.39315803365, 126434.0, 105315.13157568256, 108717.45641271742, 110763.44618276857, 111339.0, 113099.43450282696, 90865.0, 93094.0, 93126.0, 93832.53083734674, 94412.0, 9587.376820506644, 9817.754556136153, 9821.65624203178, 9823.21414286841, 9840.950795245977, 45178.096438070395, 53193.67015824635, 56560.43439565552, 58166.7091664539, 58912.23263302108, 83361.3327733454, 86549.70175447248, 86861.56569217113, 87781.0, 89023.0, 12809.159087282576, 13259.469621215043, 13494.392752326365, 13640.113392629448, 13834.23911684867, 14349.64125896861, 14458.855411446171, 14503.637409064435, 14694.55916322512, 15434.382624695336, 13494.190348579117, 13694.0, 13707.246101464116, 13714.38285277145, 13859.307034648025, 13865.376058077209, 14026.368813403216, 14250.928745356206, 14316.284185790877, 14328.641783955067, 13266.53567125146, 13364.665883352995, 13518.188908665512, 13525.59423217305, 14098.083624564371, 11152.609658661908, 11518.82721759191, 11530.769384612093, 11568.074554035826, 11620.767584648429, 9286.885573731195, 9569.186619137452, 9625.614975401186, 10192.133668637953, 11582.594609188638], "value": 7746.611532047586, "unit": "score"}], "name": "dromaeo_css", "value": 7261.481468444128, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 150.0, 154.0, 113.0, 114.0, 111.0, 112.0, 114.0, 111.0, 113.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 127.0, 198.0, 141.0, 124.0, 145.0, 136.0, 179.0, 115.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 185.0, 183.0, 183.0, 185.0, 183.0, 184.0, 182.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 107.0, 59.0, 56.0, 65.0, 64.0, 96.0, 57.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 92.0, 72.0, 93.0, 99.0, 109.0, 70.0, 67.0, 101.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 92.0, 89.0, 91.0, 87.0, 91.0, 88.0, 90.0, 95.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 84.0, 84.0, 87.0, 83.0, 85.0, 85.0, 85.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 57.0, 56.0, 54.0, 54.0, 55.0, 54.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 74.0, 89.0, 98.0, 66.0, 64.0, 81.0, 94.0, 103.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 86.0, 85.0, 82.0, 87.0, 88.0, 143.0, 87.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 136.0, 143.0, 140.0, 150.0, 144.0, 144.0, 153.0, 139.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 169.0, 168.0, 174.0, 210.0, 175.0, 169.0, 165.0, 168.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 66.0, 68.0, 66.0, 66.0, 69.0, 74.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1494.0, "alertThreshold": 2.0}]} 08:18:24 INFO - Return code: 0 08:18:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:18:24 ERROR - # TBPL SUCCESS # 08:18:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:18:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:18:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:18:24 INFO - 2016-06-02 08:18:24.660 screenresolution[1961:13744] starting screenresolution argv=screenresolution get 08:18:24 INFO - 2016-06-02 08:18:24.662 screenresolution[1961:13744] Display 0: 1600x1200x32@60 08:18:24 INFO - 2016-06-02 08:18:24.669 screenresolution[1962:13747] starting screenresolution argv=screenresolution list 08:18:24 INFO - Available Modes on Display 0 08:18:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:18:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:18:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:18:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:18:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:18:24 INFO - Intel Iris: 08:18:24 INFO - Chipset Model: Intel Iris 08:18:24 INFO - Type: GPU 08:18:24 INFO - Bus: Built-In 08:18:24 INFO - VRAM (Dynamic, Max): 1536 MB 08:18:24 INFO - Vendor: Intel (0x8086) 08:18:24 INFO - Device ID: 0x0a2e 08:18:24 INFO - Revision ID: 0x0009 08:18:24 INFO - Displays: 08:18:24 INFO - Display: 08:18:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:18:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:18:24 INFO - Display Serial Number: 1600x1200 60 08:18:24 INFO - Main Display: Yes 08:18:24 INFO - Mirror: Off 08:18:24 INFO - Online: Yes 08:18:24 INFO - Rotation: Supported 08:18:24 INFO - Return code: 0 08:18:24 INFO - Running post-action listener: _resource_record_post_action 08:18:24 INFO - ##### 08:18:24 INFO - ##### Finished run-tests step (success) 08:18:24 INFO - ##### 08:18:24 INFO - Running post-run listener: _resource_record_post_run 08:18:24 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 954368; Write bytes: 696433664; Read time: 10; Write time: 2068 08:18:24 INFO - TinderboxPrint: CPU usage
53.8% 08:18:24 INFO - TinderboxPrint: I/O read bytes / time
954,368 / 10 08:18:24 INFO - TinderboxPrint: I/O write bytes / time
696,433,664 / 2,068 08:18:24 INFO - TinderboxPrint: CPU idle
2,244.4 (46.2%) 08:18:24 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 08:18:24 INFO - TinderboxPrint: CPU user
1,643.0 (33.9%) 08:18:24 INFO - TinderboxPrint: Swap in / out
400,404,480 / 0 08:18:24 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 382913024; Write bytes: 377054720; Read time: 29422; Write time: 806 08:18:24 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 954368; Write bytes: 294258176; Read time: 10; Write time: 1213 08:18:25 INFO - Running post-run listener: _upload_blobber_files 08:18:25 INFO - Blob upload gear active. 08:18:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:18:25 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:18:25 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:18:25 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:18:25 INFO - (blobuploader) - INFO - Open directory for files ... 08:18:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:18:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:18:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:18:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:18:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:18:26 INFO - (blobuploader) - INFO - Done attempting. 08:18:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:18:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:18:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:18:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:18:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:18:27 INFO - (blobuploader) - INFO - Done attempting. 08:18:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:18:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:18:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:18:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:18:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:18:30 INFO - (blobuploader) - INFO - Done attempting. 08:18:30 INFO - (blobuploader) - INFO - Iteration through files over. 08:18:30 INFO - Return code: 0 08:18:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:18:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:18:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d40b9756f5e6a396128ba08214bf6c7e76ee031a275e377f3999c813e534f7628bdd2c09b8f14211edf8ee2960317b9e0416218d0f494f04b4ca664d758b2cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83afa684038ffb0ff3e5ff7592305a7448759d4248ff5675458f957598db47dac5aad89c3dd1f6e1c0cc37aca2805d33bf73a048b1675d9a391096f68571dd90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b06aa2d7627656b43a543f744bc48b71b9bfecbcef89d62252894bf20db92f7a262075e4e35943500b92964989d6ec453908b484c39831488373238cd18f9a02"} 08:18:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:18:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:18:30 INFO - Contents: 08:18:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d40b9756f5e6a396128ba08214bf6c7e76ee031a275e377f3999c813e534f7628bdd2c09b8f14211edf8ee2960317b9e0416218d0f494f04b4ca664d758b2cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83afa684038ffb0ff3e5ff7592305a7448759d4248ff5675458f957598db47dac5aad89c3dd1f6e1c0cc37aca2805d33bf73a048b1675d9a391096f68571dd90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b06aa2d7627656b43a543f744bc48b71b9bfecbcef89d62252894bf20db92f7a262075e4e35943500b92964989d6ec453908b484c39831488373238cd18f9a02"} 08:18:30 INFO - Running post-run listener: copy_logs_to_upload_dir 08:18:30 INFO - Copying logs to upload dir... 08:18:30 INFO - mkdir: /builds/slave/test/build/upload/logs 08:18:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.692522 ========= master_lag: 2.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-06-02 08:18:32.133317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 08:18:32.137276) ========= 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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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/3d40b9756f5e6a396128ba08214bf6c7e76ee031a275e377f3999c813e534f7628bdd2c09b8f14211edf8ee2960317b9e0416218d0f494f04b4ca664d758b2cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83afa684038ffb0ff3e5ff7592305a7448759d4248ff5675458f957598db47dac5aad89c3dd1f6e1c0cc37aca2805d33bf73a048b1675d9a391096f68571dd90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b06aa2d7627656b43a543f744bc48b71b9bfecbcef89d62252894bf20db92f7a262075e4e35943500b92964989d6ec453908b484c39831488373238cd18f9a02"} build_url:https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/bTCma_BNTaeXGBxAGvGMqA/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/3d40b9756f5e6a396128ba08214bf6c7e76ee031a275e377f3999c813e534f7628bdd2c09b8f14211edf8ee2960317b9e0416218d0f494f04b4ca664d758b2cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83afa684038ffb0ff3e5ff7592305a7448759d4248ff5675458f957598db47dac5aad89c3dd1f6e1c0cc37aca2805d33bf73a048b1675d9a391096f68571dd90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b06aa2d7627656b43a543f744bc48b71b9bfecbcef89d62252894bf20db92f7a262075e4e35943500b92964989d6ec453908b484c39831488373238cd18f9a02"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 08:18:32.186009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:18:32.186449) ========= 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.XFRt7LWhO9/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.XoJ8kBQ6XH/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.005197 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 08:18:32.250774) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 08:18:32.251120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 08:18:32.251529) ========= ========= Total master_lag: 2.17 =========