builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0337 starttime: 1464079294.6 results: success (0) buildid: 20160523171639 builduid: 2df6f63a3d754fefa021eb2c65da7322 revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:34.603275) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:34.603687) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:34.603969) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:34.629914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-24 01:41:34.630228) ========= 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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 01:41:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-05-24 01:41:34 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112483 ========= master_lag: 2.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-24 01:41:36.974491) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:36.974861) ========= 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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.024926 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:37.023108) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 01:41:37.023468) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --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 46fe2115d46a5bb40523b8466341d8f9a26e1bdf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 01:41:37,109 truncating revision to first 12 chars 2016-05-24 01:41:37,110 Setting DEBUG logging. 2016-05-24 01:41:37,110 attempt 1/10 2016-05-24 01:41:37,110 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/46fe2115d46a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 01:41:38,809 unpacking tar archive at: mozilla-central-46fe2115d46a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.956514 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 01:41:38.997685) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:38.998009) ========= script_repo_revision: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:38.998389) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:38.998672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 01:41:39.014316) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-24 01:41:39.014725) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:41:39 INFO - MultiFileLogger online at 20160524 01:41:39 in /builds/slave/test 01:41:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:41:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:41:39 INFO - {'append_to_log': False, 01:41:39 INFO - 'base_work_dir': '/builds/slave/test', 01:41:39 INFO - 'blob_upload_branch': 'Firefox', 01:41:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:41:39 INFO - 'branch': 'Firefox', 01:41:39 INFO - 'buildbot_json_path': 'buildprops.json', 01:41:39 INFO - 'config_files': ('talos/mac_config.py',), 01:41:39 INFO - 'default_actions': ('clobber', 01:41:39 INFO - 'read-buildbot-config', 01:41:39 INFO - 'download-and-extract', 01:41:39 INFO - 'populate-webroot', 01:41:39 INFO - 'create-virtualenv', 01:41:39 INFO - 'install', 01:41:39 INFO - 'run-tests'), 01:41:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:41:39 INFO - 'download_minidump_stackwalk': True, 01:41:39 INFO - 'download_symbols': 'ondemand', 01:41:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:41:39 INFO - 'tooltool.py': '/tools/tooltool.py', 01:41:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:41:39 INFO - '/tools/misc-python/virtualenv.py')}, 01:41:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:41:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:41:39 INFO - 'installer_path': 'installer.exe', 01:41:39 INFO - 'log_level': 'info', 01:41:39 INFO - 'log_name': 'talos', 01:41:39 INFO - 'log_to_console': True, 01:41:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:41:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:41:39 INFO - 'opt_config_files': (), 01:41:39 INFO - 'pip_index': False, 01:41:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:39 INFO - 'cmd': ('bash', 01:41:39 INFO - '-c', 01:41:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:39 INFO - 'enabled': True, 01:41:39 INFO - 'halt_on_failure': False, 01:41:39 INFO - 'name': 'check_screen_resolution'},), 01:41:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:39 INFO - 'cmd': ('bash', 01:41:39 INFO - '-c', 01:41:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:39 INFO - 'enabled': True, 01:41:39 INFO - 'halt_on_failure': False, 01:41:39 INFO - 'name': 'check_screen_resolution'},), 01:41:39 INFO - 'run_cmd_checks_enabled': True, 01:41:39 INFO - 'sps_profile': False, 01:41:39 INFO - 'sps_profile_interval': 0, 01:41:39 INFO - 'suite': 'dromaeojs-e10s', 01:41:39 INFO - 'system_bits': '32', 01:41:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:41:39 INFO - 'title': 't-yosemite-r7-0337', 01:41:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:41:39 INFO - 'use_talos_json': True, 01:41:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:41:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:41:39 INFO - 'work_dir': 'build'} 01:41:39 INFO - ##### 01:41:39 INFO - ##### Running clobber step. 01:41:39 INFO - ##### 01:41:39 INFO - Running pre-action listener: _resource_record_pre_action 01:41:39 INFO - Running main action method: clobber 01:41:39 INFO - rmtree: /builds/slave/test/build 01:41:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:41:40 INFO - Running post-action listener: _resource_record_post_action 01:41:40 INFO - ##### 01:41:40 INFO - ##### Finished clobber step (success) 01:41:40 INFO - ##### 01:41:40 INFO - ##### 01:41:40 INFO - ##### Running read-buildbot-config step. 01:41:40 INFO - ##### 01:41:40 INFO - Running pre-action listener: _resource_record_pre_action 01:41:40 INFO - Running main action method: read_buildbot_config 01:41:40 INFO - Using buildbot properties: 01:41:40 INFO - { 01:41:40 INFO - "project": "", 01:41:40 INFO - "product": "firefox", 01:41:40 INFO - "script_repo_revision": "production", 01:41:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 01:41:40 INFO - "repository": "", 01:41:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 01:41:40 INFO - "buildid": "20160523171639", 01:41:40 INFO - "pgo_build": "False", 01:41:40 INFO - "basedir": "/builds/slave/test", 01:41:40 INFO - "buildnumber": 284, 01:41:40 INFO - "slavename": "t-yosemite-r7-0337", 01:41:40 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:41:40 INFO - "platform": "yosemite_r7", 01:41:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:41:40 INFO - "branch": "mozilla-central", 01:41:40 INFO - "repo_path": "mozilla-central", 01:41:40 INFO - "revision": "46fe2115d46a5bb40523b8466341d8f9a26e1bdf", 01:41:40 INFO - "stage_platform": "macosx64", 01:41:40 INFO - "builduid": "2df6f63a3d754fefa021eb2c65da7322", 01:41:40 INFO - "slavebuilddir": "test" 01:41:40 INFO - } 01:41:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:41:40 INFO - Running post-action listener: _resource_record_post_action 01:41:40 INFO - ##### 01:41:40 INFO - ##### Finished read-buildbot-config step (success) 01:41:40 INFO - ##### 01:41:40 INFO - ##### 01:41:40 INFO - ##### Running download-and-extract step. 01:41:40 INFO - ##### 01:41:40 INFO - Running pre-action listener: _resource_record_pre_action 01:41:40 INFO - Running main action method: download_and_extract 01:41:40 INFO - mkdir: /builds/slave/test/build/tests 01:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:40 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:42 INFO - Downloaded 1199 bytes. 01:41:42 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:41:42 INFO - Using the following test package requirements: 01:41:42 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:42 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:41:42 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:41:42 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'jsshell-mac.zip'], 01:41:42 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:41:42 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:41:42 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:41:42 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:41:42 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:41:42 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:41:42 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:41:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:41:42 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')]} 01:41:42 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:49 INFO - Downloaded 18630520 bytes. 01:41:49 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'] 01:41:49 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 01:41:52 INFO - Return code: 0 01:41:52 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 01:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:41:52 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:41:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:41:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:42:12 INFO - Downloaded 18630520 bytes. 01:42:12 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'] 01:42:12 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 01:42:16 INFO - Return code: 0 01:42: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')]} 01:42:16 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:42:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:42:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:42:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:42:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:42:17 INFO - Downloaded 10840359 bytes. 01:42:17 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'] 01:42:17 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 01:42:17 INFO - Return code: 0 01:42: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')]} 01:42:17 INFO - https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:42:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:42:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:42:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:42:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/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 01:42:20 INFO - Downloaded 110231948 bytes. 01:42:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:42:20 INFO - mkdir: /builds/slave/test/properties 01:42:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:42:20 INFO - Writing to file /builds/slave/test/properties/build_url 01:42:20 INFO - Contents: 01:42:20 INFO - build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:42:21 INFO - Running post-action listener: _resource_record_post_action 01:42:21 INFO - Running post-action listener: set_extra_try_arguments 01:42:21 INFO - ##### 01:42:21 INFO - ##### Finished download-and-extract step (success) 01:42:21 INFO - ##### 01:42:21 INFO - ##### 01:42:21 INFO - ##### Running populate-webroot step. 01:42:21 INFO - ##### 01:42:21 INFO - Running pre-action listener: _resource_record_pre_action 01:42:21 INFO - Running main action method: populate_webroot 01:42:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:42:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:42:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:42:21 INFO - '--tppagecycles', 01:42:21 INFO - '7'], 01:42:21 INFO - 'tests': ['tsvgm']}}, 01:42:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:42:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:42:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:42:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:42:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:42:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:42:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['damp', 'tps']}, 01:42:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'tests': ['damp', 'tps']}, 01:42:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['dromaeo_dom']}, 01:42:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:42:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['a11yr', 01:42:21 INFO - 'ts_paint', 01:42:21 INFO - 'tpaint', 01:42:21 INFO - 'sessionrestore', 01:42:21 INFO - 'sessionrestore_no_auto_restore', 01:42:21 INFO - 'tabpaint']}, 01:42:21 INFO - 'other-e10s': {'tests': ['a11yr', 01:42:21 INFO - 'ts_paint', 01:42:21 INFO - 'tpaint', 01:42:21 INFO - 'sessionrestore', 01:42:21 INFO - 'sessionrestore_no_auto_restore', 01:42:21 INFO - 'tabpaint']}, 01:42:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:42:21 INFO - 'ts_paint', 01:42:21 INFO - 'tpaint', 01:42:21 INFO - 'sessionrestore', 01:42:21 INFO - 'sessionrestore_no_auto_restore', 01:42:21 INFO - 'tabpaint']}, 01:42:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:42:21 INFO - 'ts_paint', 01:42:21 INFO - 'tpaint', 01:42:21 INFO - 'sessionrestore', 01:42:21 INFO - 'sessionrestore_no_auto_restore', 01:42:21 INFO - 'tabpaint']}, 01:42:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['a11yr', 01:42:21 INFO - 'ts_paint', 01:42:21 INFO - 'tpaint', 01:42:21 INFO - 'sessionrestore', 01:42:21 INFO - 'sessionrestore_no_auto_restore', 01:42:21 INFO - 'tabpaint']}, 01:42:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['a11yr', 01:42:21 INFO - 'ts_paint', 01:42:21 INFO - 'tpaint', 01:42:21 INFO - 'sessionrestore', 01:42:21 INFO - 'sessionrestore_no_auto_restore', 01:42:21 INFO - 'tabpaint']}, 01:42:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['tsvgx', 01:42:21 INFO - 'tsvgr_opacity', 01:42:21 INFO - 'tart', 01:42:21 INFO - 'tscrollx', 01:42:21 INFO - 'cart']}, 01:42:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:42:21 INFO - 'tsvgr_opacity', 01:42:21 INFO - 'tart', 01:42:21 INFO - 'tscrollx', 01:42:21 INFO - 'cart']}, 01:42:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'talos_options': ['--disable-e10s'], 01:42:21 INFO - 'tests': ['tp5o']}, 01:42:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'tests': ['tp5o']}, 01:42:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'talos_options': ['--disable-e10s', 01:42:21 INFO - '--xperf_path', 01:42:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:42:21 INFO - 'tests': ['tp5n']}, 01:42:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:42:21 INFO - 'talos_options': ['--xperf_path', 01:42:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:42:21 INFO - 'tests': ['tp5n']}}, 01:42:21 INFO - 'talos.zip': {'path': '', 01:42:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:42:21 INFO - Running post-action listener: _resource_record_post_action 01:42:21 INFO - ##### 01:42:21 INFO - ##### Finished populate-webroot step (success) 01:42:21 INFO - ##### 01:42:21 INFO - ##### 01:42:21 INFO - ##### Running create-virtualenv step. 01:42:21 INFO - ##### 01:42:21 INFO - Running pre-action listener: _resource_record_pre_action 01:42:21 INFO - Running main action method: create_virtualenv 01:42:21 INFO - Creating virtualenv /builds/slave/test/build/venv 01:42:21 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 01:42:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:42:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:42:21 INFO - Using real prefix '/tools/python27' 01:42:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:42:22 INFO - Installing distribute.............................................................................................................................................................................................done. 01:42:25 INFO - Installing pip.................done. 01:42:25 INFO - Return code: 0 01:42:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:42:25 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')]} 01:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:25 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')]} 01:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:25 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:25 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 01:42:25 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 01:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:25 INFO - 'HOME': '/Users/cltbld', 01:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:25 INFO - 'LOGNAME': 'cltbld', 01:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:25 INFO - 'MOZ_NO_REMOTE': '1', 01:42:25 INFO - 'NO_EM_RESTART': '1', 01:42:25 INFO - 'PAGER': '/bin/cat', 01:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:25 INFO - 'PWD': '/builds/slave/test', 01:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:25 INFO - 'SHELL': '/bin/bash', 01:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:25 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:25 INFO - 'USER': 'cltbld', 01:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:25 INFO - 'XPC_FLAGS': '0x0', 01:42:25 INFO - 'XPC_SERVICE_NAME': '0', 01:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:25 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 01:42:25 INFO - Cleaning up... 01:42:25 INFO - Return code: 0 01:42:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:42:25 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')]} 01:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:25 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')]} 01:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:25 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:25 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 01:42:25 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 01:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:25 INFO - 'HOME': '/Users/cltbld', 01:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:25 INFO - 'LOGNAME': 'cltbld', 01:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:25 INFO - 'MOZ_NO_REMOTE': '1', 01:42:25 INFO - 'NO_EM_RESTART': '1', 01:42:25 INFO - 'PAGER': '/bin/cat', 01:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:25 INFO - 'PWD': '/builds/slave/test', 01:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:25 INFO - 'SHELL': '/bin/bash', 01:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:25 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:25 INFO - 'USER': 'cltbld', 01:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:25 INFO - 'XPC_FLAGS': '0x0', 01:42:25 INFO - 'XPC_SERVICE_NAME': '0', 01:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:25 INFO - Downloading/unpacking psutil>=3.1.1 01:42:25 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 01:42:25 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 01:42:25 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 01:42:25 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 01:42:25 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 01:42:25 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 01:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:42:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:42:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:42:28 INFO - Installing collected packages: psutil 01:42:28 INFO - Running setup.py install for psutil 01:42:28 INFO - building 'psutil._psutil_osx' extension 01:42:28 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 01:42:29 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 01:42:29 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 01:42:29 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 01:42:29 INFO - building 'psutil._psutil_posix' extension 01:42:29 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 01:42:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:42:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:42:29 INFO - ^ 01:42:29 INFO - 1 warning generated. 01:42:29 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 01:42:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:42:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:42:29 INFO - Successfully installed psutil 01:42:29 INFO - Cleaning up... 01:42:30 INFO - Return code: 0 01:42:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:42:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:30 INFO - 'HOME': '/Users/cltbld', 01:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:30 INFO - 'LOGNAME': 'cltbld', 01:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:30 INFO - 'MOZ_NO_REMOTE': '1', 01:42:30 INFO - 'NO_EM_RESTART': '1', 01:42:30 INFO - 'PAGER': '/bin/cat', 01:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:30 INFO - 'PWD': '/builds/slave/test', 01:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:30 INFO - 'SHELL': '/bin/bash', 01:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:30 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:30 INFO - 'USER': 'cltbld', 01:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:30 INFO - 'XPC_FLAGS': '0x0', 01:42:30 INFO - 'XPC_SERVICE_NAME': '0', 01:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:42:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:42:33 INFO - Installing collected packages: mozsystemmonitor 01:42:33 INFO - Running setup.py install for mozsystemmonitor 01:42:33 INFO - Successfully installed mozsystemmonitor 01:42:33 INFO - Cleaning up... 01:42:33 INFO - Return code: 0 01:42:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:33 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:33 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 01:42:33 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 01:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:33 INFO - 'HOME': '/Users/cltbld', 01:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:33 INFO - 'LOGNAME': 'cltbld', 01:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:33 INFO - 'MOZ_NO_REMOTE': '1', 01:42:33 INFO - 'NO_EM_RESTART': '1', 01:42:33 INFO - 'PAGER': '/bin/cat', 01:42:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:33 INFO - 'PWD': '/builds/slave/test', 01:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:33 INFO - 'SHELL': '/bin/bash', 01:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:33 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:33 INFO - 'USER': 'cltbld', 01:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:33 INFO - 'XPC_FLAGS': '0x0', 01:42:33 INFO - 'XPC_SERVICE_NAME': '0', 01:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:34 INFO - Downloading/unpacking blobuploader==1.2.4 01:42:34 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 01:42:34 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 01:42:34 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 01:42:34 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 01:42:34 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 01:42:34 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 01:42:36 INFO - Downloading blobuploader-1.2.4.tar.gz 01:42:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:42:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:42:36 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 01:42:36 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 01:42:36 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 01:42:36 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 01:42:36 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 01:42:36 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 01:42:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:42:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:42:37 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 01:42:37 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 01:42:37 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 01:42:37 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 01:42:37 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 01:42:37 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 01:42:37 INFO - Downloading docopt-0.6.1.tar.gz 01:42:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:42:37 INFO - Installing collected packages: blobuploader, requests, docopt 01:42:37 INFO - Running setup.py install for blobuploader 01:42:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:42:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:42:37 INFO - Running setup.py install for requests 01:42:38 INFO - Running setup.py install for docopt 01:42:38 INFO - Successfully installed blobuploader requests docopt 01:42:38 INFO - Cleaning up... 01:42:38 INFO - Return code: 0 01:42:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:38 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')]} 01:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:38 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')]} 01:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:38 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:38 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 01:42:38 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 01:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:38 INFO - 'HOME': '/Users/cltbld', 01:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:38 INFO - 'LOGNAME': 'cltbld', 01:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:38 INFO - 'MOZ_NO_REMOTE': '1', 01:42:38 INFO - 'NO_EM_RESTART': '1', 01:42:38 INFO - 'PAGER': '/bin/cat', 01:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:38 INFO - 'PWD': '/builds/slave/test', 01:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:38 INFO - 'SHELL': '/bin/bash', 01:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:38 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:38 INFO - 'USER': 'cltbld', 01:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:38 INFO - 'XPC_FLAGS': '0x0', 01:42:38 INFO - 'XPC_SERVICE_NAME': '0', 01:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:38 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-xKZYK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-txB3JT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-fpnUXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-BbJ3nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-IhJtap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-BxGtd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-DigJB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-tO4HjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-rUkRlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-A27Ve0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:39 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-z6kezT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:40 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-hRiatB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:40 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-YMBcma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:40 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-uHTFgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:40 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-cS3f2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:40 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-Qsx47S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:40 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-iw46yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:42:40 INFO - Running setup.py install for manifestparser 01:42:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:42:40 INFO - Running setup.py install for mozcrash 01:42:41 INFO - Running setup.py install for mozdebug 01:42:41 INFO - Running setup.py install for mozdevice 01:42:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Running setup.py install for mozfile 01:42:41 INFO - Running setup.py install for mozhttpd 01:42:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Running setup.py install for mozinfo 01:42:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Running setup.py install for mozInstall 01:42:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:42:41 INFO - Running setup.py install for mozleak 01:42:42 INFO - Running setup.py install for mozlog 01:42:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:42:42 INFO - Running setup.py install for moznetwork 01:42:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:42:42 INFO - Running setup.py install for mozprocess 01:42:42 INFO - Running setup.py install for mozprofile 01:42:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:42:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:42:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:42:42 INFO - Running setup.py install for mozrunner 01:42:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:42:42 INFO - Running setup.py install for mozscreenshot 01:42:42 INFO - Running setup.py install for moztest 01:42:43 INFO - Running setup.py install for mozversion 01:42:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:42:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:42:43 INFO - Cleaning up... 01:42:43 INFO - Return code: 0 01:42:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42: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')]} 01:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42: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')]} 01:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:43 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:43 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 01:42:43 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 01:42:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:43 INFO - 'HOME': '/Users/cltbld', 01:42:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:43 INFO - 'LOGNAME': 'cltbld', 01:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:43 INFO - 'MOZ_NO_REMOTE': '1', 01:42:43 INFO - 'NO_EM_RESTART': '1', 01:42:43 INFO - 'PAGER': '/bin/cat', 01:42:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:43 INFO - 'PWD': '/builds/slave/test', 01:42:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:43 INFO - 'SHELL': '/bin/bash', 01:42:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:43 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:43 INFO - 'USER': 'cltbld', 01:42:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:43 INFO - 'XPC_FLAGS': '0x0', 01:42:43 INFO - 'XPC_SERVICE_NAME': '0', 01:42:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:43 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-I5ONGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:43 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)) 01:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:43 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-gvUXRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:43 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)) 01:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:43 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-IuG3ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:43 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)) 01:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:43 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-F_lKsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:43 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)) 01:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:43 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-aZA2Cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-08Gboy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-leWrVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-epYXIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-D6Cl79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-j_KMpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-_41qM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-_d6eSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-2oR55U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:44 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)) 01:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:44 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-IBALyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:45 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)) 01:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:45 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-IzQUnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:45 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)) 01:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:45 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-aSe15p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:45 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)) 01:42:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:45 INFO - Running setup.py (path:/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/pip-gIpVF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:45 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)) 01:42:45 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)) 01:42:45 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)) 01:42:45 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)) 01:42:45 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)) 01:42:45 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)) 01:42:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:47 INFO - Downloading blessings-1.6.tar.gz 01:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:42:48 INFO - Installing collected packages: blessings 01:42:48 INFO - Running setup.py install for blessings 01:42:48 INFO - Successfully installed blessings 01:42:48 INFO - Cleaning up... 01:42:48 INFO - Return code: 0 01:42:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:42:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:48 INFO - Reading from file tmpfile_stdout 01:42:48 INFO - Current package versions: 01:42:48 INFO - blessings == 1.6 01:42:48 INFO - blobuploader == 1.2.4 01:42:48 INFO - docopt == 0.6.1 01:42:48 INFO - manifestparser == 1.1 01:42:48 INFO - mozInstall == 1.12 01:42:48 INFO - mozcrash == 0.17 01:42:48 INFO - mozdebug == 0.1 01:42:48 INFO - mozdevice == 0.48 01:42:48 INFO - mozfile == 1.2 01:42:48 INFO - mozhttpd == 0.7 01:42:48 INFO - mozinfo == 0.9 01:42:48 INFO - mozleak == 0.1 01:42:48 INFO - mozlog == 3.2 01:42:48 INFO - moznetwork == 0.27 01:42:48 INFO - mozprocess == 0.22 01:42:48 INFO - mozprofile == 0.28 01:42:48 INFO - mozrunner == 6.11 01:42:48 INFO - mozscreenshot == 0.1 01:42:48 INFO - mozsystemmonitor == 0.3 01:42:48 INFO - moztest == 0.7 01:42:48 INFO - mozversion == 1.4 01:42:48 INFO - psutil == 3.1.1 01:42:48 INFO - requests == 1.2.3 01:42:48 INFO - wsgiref == 0.1.2 01:42:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:48 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')]} 01:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:48 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')]} 01:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:48 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:48 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 01:42:48 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 01:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:48 INFO - 'HOME': '/Users/cltbld', 01:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:48 INFO - 'LOGNAME': 'cltbld', 01:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:48 INFO - 'MOZ_NO_REMOTE': '1', 01:42:48 INFO - 'NO_EM_RESTART': '1', 01:42:48 INFO - 'PAGER': '/bin/cat', 01:42:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:48 INFO - 'PWD': '/builds/slave/test', 01:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:48 INFO - 'SHELL': '/bin/bash', 01:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:48 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:48 INFO - 'USER': 'cltbld', 01:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:48 INFO - 'XPC_FLAGS': '0x0', 01:42:48 INFO - 'XPC_SERVICE_NAME': '0', 01:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 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)) 01:42:48 INFO - Cleaning up... 01:42:48 INFO - Return code: 0 01:42:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:42:48 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')]} 01:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:48 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')]} 01:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:48 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 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:48 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 01:42:48 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 01:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:48 INFO - 'HOME': '/Users/cltbld', 01:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:48 INFO - 'LOGNAME': 'cltbld', 01:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:48 INFO - 'MOZ_NO_REMOTE': '1', 01:42:48 INFO - 'NO_EM_RESTART': '1', 01:42:48 INFO - 'PAGER': '/bin/cat', 01:42:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:48 INFO - 'PWD': '/builds/slave/test', 01:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:48 INFO - 'SHELL': '/bin/bash', 01:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:48 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:48 INFO - 'USER': 'cltbld', 01:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:48 INFO - 'XPC_FLAGS': '0x0', 01:42:48 INFO - 'XPC_SERVICE_NAME': '0', 01:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:49 INFO - Downloading/unpacking jsonschema 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42: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 01:42:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:42:51 INFO - Downloading/unpacking functools32 (from jsonschema) 01:42:51 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 01:42:51 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 01:42:51 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 01:42:51 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 01:42:51 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 01:42:51 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 01:42:51 INFO - Downloading functools32-3.2.3-2.tar.gz 01:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:42:51 INFO - warning: no files found matching '*.txt' 01:42:51 INFO - no previously-included directories found matching 'build' 01:42:51 INFO - no previously-included directories found matching 'dist' 01:42:51 INFO - no previously-included directories found matching '.git*' 01:42:51 INFO - Installing collected packages: jsonschema, functools32 01:42:52 INFO - Running setup.py install for functools32 01:42:52 INFO - warning: no files found matching '*.txt' 01:42:52 INFO - no previously-included directories found matching 'build' 01:42:52 INFO - no previously-included directories found matching 'dist' 01:42:52 INFO - no previously-included directories found matching '.git*' 01:42:52 INFO - Successfully installed jsonschema functools32 01:42:52 INFO - Cleaning up... 01:42:52 INFO - Return code: 0 01:42:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103397a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10339d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0c94cea00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, '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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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 01:42:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:42:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:42:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:42:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:52 INFO - 'HOME': '/Users/cltbld', 01:42:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:52 INFO - 'LOGNAME': 'cltbld', 01:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:52 INFO - 'MOZ_NO_REMOTE': '1', 01:42:52 INFO - 'NO_EM_RESTART': '1', 01:42:52 INFO - 'PAGER': '/bin/cat', 01:42:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:52 INFO - 'PWD': '/builds/slave/test', 01:42:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:52 INFO - 'SHELL': '/bin/bash', 01:42:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:42:52 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:42:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:52 INFO - 'USER': 'cltbld', 01:42:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:52 INFO - 'XPC_FLAGS': '0x0', 01:42:52 INFO - 'XPC_SERVICE_NAME': '0', 01:42:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:52 INFO - Downloading/unpacking flake8 01:42:52 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 01:42:52 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 01:42:52 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 01:42:52 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 01:42:52 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 01:42:52 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 01:42:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:42:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:42:54 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 01:42:54 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 01:42:54 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 01:42:54 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 01:42:54 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 01:42:54 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 01:42:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:42:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:42:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:56 INFO - no previously-included directories found matching 'docs/_build' 01:42:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:42:56 INFO - Running setup.py install for pep8 01:42:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:42:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:42:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:42:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:42:56 INFO - no previously-included directories found matching 'docs/_build' 01:42:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:42:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:42:56 INFO - Cleaning up... 01:42:56 INFO - Return code: 0 01:42:56 INFO - Running post-action listener: _resource_record_post_action 01:42:56 INFO - Running post-action listener: _start_resource_monitoring 01:42:56 INFO - Starting resource monitoring. 01:42:56 INFO - ##### 01:42:56 INFO - ##### Finished create-virtualenv step (success) 01:42:56 INFO - ##### 01:42:56 INFO - ##### 01:42:56 INFO - ##### Running install step. 01:42:56 INFO - ##### 01:42:56 INFO - Running pre-action listener: _resource_record_pre_action 01:42:56 INFO - Running main action method: install 01:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:56 INFO - Reading from file tmpfile_stdout 01:42:56 INFO - Detecting whether we're running mozinstall >=1.0... 01:42:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:42:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:42:56 INFO - Reading from file tmpfile_stdout 01:42:56 INFO - Output received: 01:42:56 INFO - Usage: mozinstall [options] installer 01:42:56 INFO - Options: 01:42:56 INFO - -h, --help show this help message and exit 01:42:56 INFO - -d DEST, --destination=DEST 01:42:56 INFO - Directory to install application into. [default: 01:42:56 INFO - "/builds/slave/test"] 01:42:56 INFO - --app=APP Application being installed. [default: firefox] 01:42:56 INFO - mkdir: /builds/slave/test/build/application 01:42:56 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'] 01:42:56 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 01:43:30 INFO - Reading from file tmpfile_stdout 01:43:30 INFO - Output received: 01:43:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:43:30 INFO - Running post-action listener: _resource_record_post_action 01:43:30 INFO - ##### 01:43:30 INFO - ##### Finished install step (success) 01:43:30 INFO - ##### 01:43:30 INFO - ##### 01:43:30 INFO - ##### Running run-tests step. 01:43:30 INFO - ##### 01:43:30 INFO - Running pre-action listener: _resource_record_pre_action 01:43:30 INFO - Running main action method: run_tests 01:43:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:43:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:43:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:43:30 INFO - 2016-05-24 01:43:30.683 screenresolution[1647:7646] starting screenresolution argv=screenresolution get 01:43:30 INFO - 2016-05-24 01:43:30.686 screenresolution[1647:7646] Display 0: 1600x1200x32@60 01:43:30 INFO - 2016-05-24 01:43:30.691 screenresolution[1648:7648] starting screenresolution argv=screenresolution list 01:43:30 INFO - Available Modes on Display 0 01:43:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:43:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:43:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:43:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:43:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:43:30 INFO - Intel Iris: 01:43:30 INFO - Chipset Model: Intel Iris 01:43:30 INFO - Type: GPU 01:43:30 INFO - Bus: Built-In 01:43:30 INFO - VRAM (Dynamic, Max): 1536 MB 01:43:30 INFO - Vendor: Intel (0x8086) 01:43:30 INFO - Device ID: 0x0a2e 01:43:30 INFO - Revision ID: 0x0009 01:43:30 INFO - Displays: 01:43:30 INFO - Display: 01:43:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:43:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:43:30 INFO - Display Serial Number: 1600x1200 60 01:43:30 INFO - Main Display: Yes 01:43:30 INFO - Mirror: Off 01:43:30 INFO - Online: Yes 01:43:30 INFO - Rotation: Supported 01:43:30 INFO - Return code: 0 01:43:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:43:30 INFO - Python 2.7.3 01:43:30 INFO - Return code: 0 01:43:30 INFO - grabbing minidump binary from tooltool 01:43:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:43:30 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 0x10323bd30>, '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 0x10305fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103232b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:43:30 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 01:43:30 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 01:43:30 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 01:43:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:43:31 INFO - Return code: 0 01:43:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:43:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:43:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:43:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:43:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:43:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:43:32 INFO - Return code: 0 01:43:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:43:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0337 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:43:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Xh5V2K7xE/Render', 01:43:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:43:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:43:32 INFO - 'HOME': '/Users/cltbld', 01:43:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:43:32 INFO - 'LOGNAME': 'cltbld', 01:43:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:43:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:43:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:43:32 INFO - 'MOZ_NO_REMOTE': '1', 01:43:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:43:32 INFO - 'NO_EM_RESTART': '1', 01:43:32 INFO - 'PAGER': '/bin/cat', 01:43:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:43:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:43:32 INFO - 'PWD': '/builds/slave/test', 01:43:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:43:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:43:32 INFO - 'SHELL': '/bin/bash', 01:43:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Es8JwHLAgF/Listeners', 01:43:32 INFO - 'TMPDIR': '/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/', 01:43:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:43:32 INFO - 'USER': 'cltbld', 01:43:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:43:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:43:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:43:32 INFO - 'XPC_FLAGS': '0x0', 01:43:32 INFO - 'XPC_SERVICE_NAME': '0', 01:43:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:43:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0337', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:43:32 INFO - mozversion application_buildid: 20160523171639 01:43:32 INFO - mozversion application_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:43:32 INFO - mozversion application_display_name: Nightly 01:43:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:43:32 INFO - mozversion application_name: Firefox 01:43:32 INFO - mozversion application_remotingname: firefox 01:43:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:43:32 INFO - mozversion application_vendor: Mozilla 01:43:32 INFO - mozversion application_version: 49.0a1 01:43:32 INFO - mozversion platform_buildid: 20160523171639 01:43:32 INFO - mozversion platform_changeset: 46fe2115d46a5bb40523b8466341d8f9a26e1bdf 01:43:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:43:32 INFO - mozversion platform_version: 49.0a1 01:43:32 INFO - using testdate: 1464079412 01:43:32 INFO - actual date: 1464079412 01:43:32 INFO - starting webserver on 'localhost:49280' 01:43:32 INFO - SUITE-START | Running 2 tests 01:43:32 INFO - TEST-START | dromaeo_css 01:43:32 INFO - operating with platform_type : mac_ 01:43:32 INFO - Initialising browser for dromaeo_css test... 01:43:32 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpLISZGx/profile http://localhost:49280/getInfo.html) 01:43:35 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 01:43:35 INFO - PROCESS | 1666 | colorDepth:24 01:43:35 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 01:43:35 INFO - PROCESS | 1666 | __metrics 01:43:35 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 01:43:36 INFO - TEST-INFO | 1666: exit 0 01:43:36 INFO - Browser initialized. 01:43:36 INFO - Running cycle 1/1 for dromaeo_css test... 01:43:36 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpLISZGx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:43:48 INFO - PROCESS | 1669 | RSS: Main: 250163200 01:43:48 INFO - PROCESS | 1669 | 01:44:37 INFO - PROCESS | 1669 | 1464079477651 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] 01:46:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 01:46:49 INFO - PROCESS | 1669 | RSS: Main: 261443584 01:46:49 INFO - PROCESS | 1669 | 01:49:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 01:49:52 INFO - PROCESS | 1669 | RSS: Main: 273543168 01:49:52 INFO - PROCESS | 1669 | 01:52:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 01:52:55 INFO - PROCESS | 1669 | RSS: Main: 275562496 01:52:55 INFO - PROCESS | 1669 | 01:55:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 01:55:57 INFO - PROCESS | 1669 | RSS: Main: 277336064 01:55:57 INFO - PROCESS | 1669 | 01:59:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 01:59:00 INFO - PROCESS | 1669 | RSS: Main: 280354816 01:59:00 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 02:02:03 INFO - PROCESS | 1669 | RSS: Main: 281137152 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | __start_tp_report 02:02:03 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 02:02:03 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 02:02:03 INFO - PROCESS | 1669 | |i|pagename|runs| 02:02:03 INFO - PROCESS | 1669 | |0;dojo.html;3501.982490087549;3527.7001454876336;3548.237129017261;3573.9463908041384;3583.2833433313344;5783.76864925403;5789.581552519633;5904.350521442649;5921.940780592193;5951.137085122666;5716.599838011341;5721.942780572193;5787.50806181474;5869.882602347961;5928.02187639039;5238.083335416296;5277.947220527803;5388.568914486851;5419.214213938996;5435.673859568419;404.2876830741948;5314.813981510648;5337.839864804062;5364.597655175858;5382.811601593945;5306.310179676637;5328.573714102882;5348.946510534884;5364.22218778275;5368.785248590071;4387.276099443588;4407.955920440787;4720.716756994591;5309.946900531023;5428.104362780136;5218.634695571312;5256.26412302278;5321.122014867542;5351.892962140774;5376.8118115865755;4513.300438432048;5705.6006079574445;5730.971345143247;5771.797987070474;5771.971140144315;6202.34875338092;6203.689815509207;6279.591826531307;6294.118823364733;6307.400796924285;6217;6246.750129994794;6296.087067375251;6339.619622822645;6357.141785858896;5984.551158663117;6023.427774361428;6039.4564489195745;6050.42520960508;6060.606060606046;6248.4064013918605;6349.52378571609;6436.678166091677;6480.2547707013355;6504.5446818723185;6171.907421388682;6197.4732147767945;6203.100550420254;6210.130581718563;6212.409821066992;6310.148130002391;6333.20834895638;6382.5532212744665;6403.391677790602;6403.775867844696;5228.320318358665;6157.291911430153;6171.537134714825;6213;6252.062190671345;6236.563440559208;6260.843478912972;6283.3088360280335;6288.213973253343;6297.086922396227;5091.439941606421;5093;5096.821611243588;5100.030994111181;5102.872428189325;3501.7373696972827;4453.398791163151;4510.233260345749;4514.887127821765;4541.863744087683;5597.860053498695;5777.653340799481;5919.881602368015;5925.4074592541265;5998.220231369894;6340;6352.078948552434;6353.96823015882;6366.395192456709;6417.133686952202;5776.508996735324;5876.265466816648;5933.169356290123;5938.881222375615;6006.12911127884;5493.533049690741;5517.2551705519245;5539.612227144061;5560.165975103767;5593.8601534961135;6588.934110658928;6599.274079851117;6605.70274337656;6623.768168114189;6627.403533681992;6208.286047104641;6227.657478838707;6253.530985176039;6263.27972283175;6329.2404911409085;6540.50946179029;6547.148870646785;6565.901511477236;6578.276389597045;6586.670666466562;6271.090691849748;6283.340249273943;6318.462930650946;6326.177596912372;6330.841728956629;4664.440267167962;5201.947980520146;6221.2845522765465;6223.564350495421;6230.345813689679;5937.317208520903;6205.937940620536;6213.9067913980425;6241.063840423791;6261;6084.90872636902;6205.720742566505;6225.937740622536;6237.282712488122;6251.093591429127;5105.693658380509;5964.194833697569;6125.11336343068;6128.9693551531955;6135.7238924249405;6181.381861813783;6241.531885108726;6251.187345645037;6252.468540174136;6252.9374706254175;4773.3555969945;5996.310424301087;6097.237845269341;6195.504359651127;6218.968905155445 02:02:03 INFO - PROCESS | 1669 | |1;ext.html;513.268592256035;514.6166106250841;538.036913924076;560.5711630602588;563.8252141836031;40515.98710032247;43488;43507.34738978918;43523.56476435235;43686;44311.335329970076;44817.77591112048;44865.55134448654;44883.77558112205;45106.54893451073;8468.491890486559;9645.662401815938;9862.90137098627;9889.357191782547;9899.15857152143;9643.180329671986;9721.854172187423;9730.075642813921;9761.463119528424;9765.414075155479;9724.854127188099;9744.805103897954;9791.16775074116;9795.461249631267;9798.412095274307;9303.720888373326;9677.27420443466;9706.854397184048;9749.463779492125;9779.951100244452;9644.517774111266;9674.951625241829;9745.171660408874;9749.756256093584;9751.804963900753;40728.79635601833;41157.176856462705;41375.75872723823;41426;41767.37348939768;2814.535601625729;2817.4646817104685;3476.443768996948;3567.23304489536;3667.6699097081123;29893.70106298931;29937.550936735966;30328.54507182394;30349.848250758605;30474.933377331687;2730.344717267861;2741.9588706169425;2745.6156138140677;2753.3804893898914;2843.5734639803986;519761;531293;532814.9999999962;533828;534323;35266.29467410689;61993.76012479725;62344.37655623476;62347.06479402813;62403.6879815598;36162.45756313657;36429.27141457156;37329.626703733156;37434.812825935696;37516.624833751855;15087.24563771807;15371.846281537266;15523.301451434712;15600.765988510182;15628.140452275235;13036.608901732963;13114.409851556702;13126.803097953538;13132.671683207996;13164.736705266032;7904.32813210872;8010.519368837889;8010.759677209693;8039.678412863537;8070.9192908071345;3319.286353434023;3336.5328853960464;3352.7820691654965;3352.798832070084;3393.1347506386205;849.8442118718538;958.1185309515263;972.4700038479045;1006.0844631385404;1033.0702367868828;25300;29957.850210748806;30114.84942575273;30641.64120471385;30794.69205307963;1040.484959944817;1045.764702941832;1078.2344535379812;1097.9560817567367;1192.4097571702064;13742;15030.098194108388;15717.135557544216;15789.68420631581;16073.517794466185;683837;689198;695515;698011;698775;32402.513962290584;44132.779336103114;44617.55382446199;44869.32696009563;45105.32342014872;1249.4752204074312;1423.4448565059636;1437.1089924247033;1438.518096437684;1460.7881857130444;29977.346968112055;30158.54762178613;30295.848520757256;30311.545326819672;30781.076567703003;45233.547664522936;45771.77114114408;55099.44900551104;55467;55480;571373;572347;577381.1130944318;581970;584835;26922;27172.32069198286;27374.315642109108;27834.44331113326;27901.860490697418;47321;47370.763146185425;47500.2874956869;48171.5182848167;48384;33151.33697325992;39089.21821563496;39372.80313598413;39450.2109957805;40119.1976160481;33253;38963.61036389713;39016.80491597524;39553.406698898965;40095.1980960385;35170.29659406746;38050.85847424582;38716.61283387243;38842;39362.2127557453;31089;32108.518372224902;34193;34331.82834085914;35006;25142.74857251404;25433.745662543137;25604.103856364938;25783.355416114;25883.352916177246 02:02:03 INFO - PROCESS | 1669 | |2;jquery.html;384.9730518863679;388.7123528588844;390.03660957435136;391.54971782450195;411.90732085280814;19773.901130494345;20431.080601372938;20850.582988340244;20963;21226.787732122673;20057;20409.795902041013;20472.28347007855;20824.68762968553;20948.057337419814;5449.209864569645;5886.234789477362;5891.675957822318;5898.88202235955;5914.585978981451;5543.6396634218845;5726.427357264281;5798.478136967672;5814.912776308359;5828.6502809831545;4821.951780482185;5756.74094665739;5757.395473475288;5784.479396854283;5795.623284486495;5624;5631.57763167762;5676.148577713335;5719.942800572004;5756;4997.700137991732;5056.82301119459;5079.949200507985;5081.644284900059;5102.387713474407;12989.22064676113;22530.324090277154;23868.88065559661;23990.323833036375;24171.637425438457;2052.640787862133;2095.9371218863307;2159.449340418199;2397.7602239775883;2462.827602067856;9665.226781857435;9776.608935642565;9857.260705447048;9905.702828915144;9914.107730304235;1723.0953749281603;1787.4101546489758;1802.387188355952;1807.5752198233404;1852.6943054396008;82324.17675823225;116725;117036.41481792621;120917;125055;11346.205765596495;11472.369019703829;11644.068474522019;11655.650330490098;11790.056795456343;14525.636859078608;14581.854181458262;14610.26948652563;14638.780418293734;14680.55958321252;8499.74500764978;8532.57337133141;8684.305255579538;8779.956100219586;8795.428297160792;6991.440684745208;8500;8572.057073721886;8588.227059564544;8641.228548147741;3784.4701741756176;4494.4606647202545;4591.104734576726;4620.792064357045;4665.2535594304745;1780.5370603643146;1949.8440124789984;1954.638391897475;1956.9510762230773;1959.617874514456;725.2747252747253;819.8155415031689;843.7468759372164;942.9198518126036;1012.5595366015807;16792.824502284722;18413;18829.52926176829;19822.108005139802;19978.30075947335;743.6876511864922;864.4035615425337;967.1537404770826;971.8299297622888;975.8487434447673;10133.08802207805;10309.74225644365;10471.738206544896;10477.42374169428;10558.683239502674;535372;539062;547024;547731;547981;121219;123394.76605233832;124570.75429245591;125322.79707767967;125519.3724031374;1015.253788465492;1031.5306535526317;1055.4353420919772;1083.2254937719567;1084.2301965604352;15812.367505299659;17222;17327.133643318037;17468.30126794914;17581.472555822835;48577.75711121422;48590;48702;49524.75237623789;50257.24614130864;68699.96950045653;70303;71054.93417598844;73940.63029684818;74367.62816185884;6907.412869905913;7267.563946163247;7319.45104117204;7339.8532029360185;7927.960360198162;7994.760157195294;8972.619695994661;10330;10589;12841.229526228457;9591.088846559565;10506.842397363893;10756.731081723019;11078.556857725924;11268.211225214156;9582.472963987047;10097.242706796873;10673.89326106729;10734.409607471664;11002.614908478165;11168.21822472419;11189.832152517882;11375.31748095117;11476.942615286871;11499.137564682514;6135.815925522242;7279.162896266997;7402.111746590228;7467.402607791472;7635.770926872203;10153.593856245667;10976.615818446317;11043.723906902393;11130;11333.150013748836 02:02:03 INFO - PROCESS | 1669 | |3;mootools.html;1557.3692654474935;1568.215892053973;1608.5817687401275;1914.0716752375104;1920.8463322934167;8145.226203510657;8653.61058752356;8667.653293868245;9210.40132391394;9221.953890230548;7516.586587737672;8768.123187681229;8803.20771130598;8929.973053098896;9028.14232647899;2370.893309801063;2407.277816655005;2417.0694282701197;2418.3712234818986;2418.407490164912;2365.373176108324;2370.8221883358733;2393;2394.449276666368;2395.4370722880108;2004.4587961250454;2381.583222935988;2388.9641655375094;2409.650600662903;2418.9516209675885;2355.281639100074;2365.775251351119;2368.7039120109985;2381.2022972304385;2388.5461762265113;2331.603627383349;2388.1402695029774;2390.8445951013127;2394.042383046778;2396.544656515274;8904.243139333164;9013.278937684969;9025.323100767402;9029.367944243906;9060.365774395796;5338.519533241988;5463.453654634544;5507.834764957018;5517.972410137923;5524.696141712204;3568.429051351797;3604.981975090108;3607.783532988029;3665.816709164558;3666.138457462494;994.6568433890296;1026.343140390151;1033.390299723167;1039.0752230514847;1052.8631277933896;10919.12646988239;11787.705307367298;11844.170908036442;12008;12053.819192712028;4819.759012049383;4839.879003024953;4918.385201849697;4923.950760492421;4934.679245849008;10038;10048.497575121215;10140.645077422403;10145.391276523433;10151.035651613085;4383.736975781463;4783.234682450791;5483.75323110461;5493.258410114663;5495.093309603871;4467.7319360838455;4822.517748225149;4945.9258111128365;4963.354763880745;4995.725235112031;4063.605962815266;4067.3085575452246;4116.094459218967;4121.278776214151;4137.048478849822;1115.8828323026128;1309.18830325199;1321.4251800466827;1323.9404226809816;1333.4399552188104;846.699421705298;903.7198468474794;912.3750231091724;915.3363811236933;938.4979036215592;5796.623219490804;5833.445822646841;5838.328592211865;5963.5825492216;5989.790357337472;428.79846472158033;435.61012893460173;438.39900246908894;442.1267995708514;442.70560077548714;3857.5756666766633;3919.235749028912;3982.9601703983167;3990.980045099756;4013.618706222904;10640.148788096934;10986.176036797273;11003.394813285186;11015.669529914116;11050.447477626087;10402.323848949945;10820.621278255223;10839.349639021684;10927.945360273148;10972.067374273276;8288.21261980111;8311.501309921425;8314.043884953307;8399.328053755808;8421.747347579583;5790.884182316414;5797.5361971041375;5845.240118784531;5886.470217680429;5927.110933360031;9982.750431239278;10129.797404052026;10152.03555662211;10232.846507302545;10395.480225988822;10075.244356673133;10192.541335640062;10305.53625086888;10330.431826249629;10422.062014418738;7512.849743005;7529.397648188241;7561.886571701319;7630.313271805564;7677.808054798675;7471.290227428385;8217.79455513617;8234.217749313806;8236.83526329458;8246.216609422096;7061.752838650623;7461.701531938662;7477.738279160203;7528.811779705551;7551.207123251979;7223.927760722536;7304.744333948286;7327.523710958771;7420.6660700269695;7474.92525074764;6948.166220053626;7002;7019.473539484661;7022.473314501331;7033.29667033313;4769.42766867978;4898.951010489992;4966.106100901728;4985.9501404985485;4998.750062496933;4691.390119284471;4752.358431611827;4756;4794.280857871207;4805.495422980537 02:02:03 INFO - PROCESS | 1669 | |4;prototype.html;307.24571177758605;318.9473736833423;320.2314445331204;325.2064961493956;328.128817988241;5620.606557540974;5662.773489060438;5678.488935995754;5683.829485115442;5692.743826527806;5006.749662516869;5553.222548843165;5569;5583.916241256385;5591.161325801132;3968;4104.425380446725;4195.643370313527;4211.22092412903;4232.703710740249;3714.4985426967282;3953.4581513209873;3995.9600403996023;4194.161167766443;4214.557471465498;3697.907552311187;3918.471006414126;3974.3641017437217;4195.916081678364;4208.158368326332;3727.9627203727887;3901.122247494291;4160.375943608453;4193.559676233982;4208.200441916026;3270.3698894227387;3325.8835940741956;3589.7974178650184;3612.584552776438;3613.4579813027995;5343.759530821084;5375.75809088592;5388.976094542064;5401.189821526763;5442.4489020628935;4014.6386825185873;4063;4590.517995610447;4780.641451891122;4878.243872199815;5413.188021796723;5627.240322556484;5674.716264186774;5929.258842644669;5962.791302304442;3693.2613477304376;3702.5186725725484;3878.8448462061483;3984.8406063757416;3993.980030099831;5122.436531981479;5754.043625167463;5845.5908086433965;5937.346891841894;6078.240219972459;3976.4234186042863;3990.6607938324987;4011.6502720228355;4021.8391264349684;4038.7778672172726;3518.612952575215;4078.6533144683035;4086.1827634473225;4087.529934057562;4092.815823287959;4193.496780386373;4750.311204875343;4763.95236047642;4796.35249241348;4801.207800712843;5223.582113430916;5363.597730170253;5924.940750592526;5970.49250813677;5986.2217911671205;1243.9426487485746;1371.5953793630704;1399.2514005007272;1399.6221020324456;1401.4114072089747;843.2958479669545;857.4512312120248;861.3152543727751;862.4260724178399;905.6875377994713;4769.618430525549;5276.15581506957;5484.780608775684;5487;5510.1459273812625;5988.053882488209;5998.370171131968;6034.969825150934;6035.969820150959;6084.574079814459;5090.516400941979;5149.382074151051;5206.427292997767;5416.106342453451;5541;2810.564362523812;2965.4513914925806;3168.651448340681;3204.903852884417;3244;5745.511631511285;5874.089516124922;5974.312954010257;6178.845528861814;6280.806646737105;5894.705264736746;5959.523238140939;6232.688365581703;6235.158253635792;6267.310595834455;5829.621074630136;6000.459958603747;6023.608465449732;6311.526635502264;6340.9365906340345;4927.408710954708;5820.447057529528;6048.60684055535;6050.304215015153;6060.484858786876;4950.41586692255;5331.413544510023;5935.379418554765;6006.219191505251;6011.617328014669;5363.919541206807;6085.878282434238;6287.30839607652;6307.243130824147;6355.841103972438;3595.0113718727557;3950.0644684105155;3956.4658771065538;3971.900702482461;3973.5033120859894;5655.264815573949;5743.0811070228565;6024.819255422344;6102.986014615255;6131.693415329305;4482.719003351856;5234.345706786652;5601.467860553241;5685.829425117253;5852.3269823969085;5182.611304152279;5210.114280572236;5777.3933736957015;5823.854403639943;5846.210761547307;4977.651564390458;5475.808346707846;5580.581456390868;5765.855853603525;5790.218320526675;5535.557155427637;5878.970605146947;5906.8440019391755;5911.586188966903;5928.666050138684;5009.924851127309;5150.9227361588855;5575.135853942646;5598.692071936082;5802.941970580409 02:02:03 INFO - PROCESS | 1669 | |5;yui.html;650.4438704907304;658.6278752504834;659.9175103112111;717.3292971072048;761.8438220164867;14644.414223431064;14810.969979786905;15392.692146157071;15415.537533873987;15431.92284038581;14388.136711797273;14443.277836108178;14444.855551444482;14461.927690361534;14474.203918784462;706.5725236232082;864.9048604653483;865.1046167216932;887.7336798960318;890.781758469176;850.0776657326808;877.784942689042;889.6530353162271;892.1747383670112;893.3210759822549;895.1496078725205;944.5466176235411;948.0424770981324;950.1686024728363;951.4386511957944;891.3671293381707;936.283742936654;941.1153515695224;941.2799208605422;943.3820847345;658.3613894522306;660.1615947746383;666.9299723529008;687.22000529399;701.6772284749021;14900.403983840633;15055.54833355001;15144.394224231017;15191.164485953268;15376.154311512862;12667.556635517805;12755.744885102338;12769.553065642658;12795.104342696015;12929.676758081028;9952.353097048668;10007.09936105754;10081.294309398345;10299.124574411184;10470.42412667303;3470.305938812248;3504.036389992747;3509.736769742254;3516.1033936346325;3518.1204698825295;76506.91613964006;82766;82864;83262;83898;9238.90761092394;9408.858867116998;9417.811643767087;9472.005439428898;9698.660546880825;21374.572508549743;21405.571888562452;21745.891270543547;21966.45083872884;22002.669959950614;7254.5647261164495;7322.0847394075745;7365.410767138616;7425.8143546411775;7580.3177714004905;3833.654971052563;3899.337112690849;3920.157166209279;3925.3130702127014;3930.508686414198;3417.9829100854336;3460.3598334308235;3482.8606855725993;3520.471929210639;3520.859165633346;581.3401788969466;617.9938200618026;631.0376675569793;631.8388810853295;642.8971364581645;517.301642782241;582.2692520886261;593.7981086430634;599.4245524296637;616.2081724983375;6507.544471886922;8059.879101813477;8110.43226974118;8124.918750812534;8252.752417427366;536.9087255166553;565.4204440448573;567.1322875999728;587.670904293597;608.2700759089022;3903.1413089120347;3906.4140378942816;3931.4692516510477;3998.980005099956;4068.9389659155136;94006;97362.53956190663;99509;99988;101993;78658.6067069661;81860;83672.74490882641;84310.57844710737;88563;62940;63128.73742525124;67118.99321510181;74540.62729686426;76879.61560192163;6877;7445.585338785613;7706.922930770621;7835;7858.528488290721;45623.31565026593;45943.162273510025;46251.306230407245;46838.76580617075;47653;55944.72027639836;57061.71469142628;63839;64134;65558.36104097436;10391.220658450799;10485.9475702621;10511.474426278503;10553.997370249814;10874.945625271823;10952.014318711354;11177.396841796859;11346.0355869752;11420.31478111316;11550.249233799776;10603.89396106029;10621.468926553487;10831.350118992885;10923.344599324066;10928.890711092787;10666.89333106659;10893.237473376788;10901.945490272497;10949.83575246356;11012.724681883017;10302.896971030194;10436.16510679159;10574.577016919236;10608.310459820086;11000.834987475035;9048.864267035868;9214.124658157465;9298.488583127722;9317.534123293945;9452.291078169028;7494.100707915085;8469.745907622535;8845.159709827556;8876.467411955304;8947.731568052968 02:02:03 INFO - PROCESS | 1669 | __end_tp_report 02:02:03 INFO - PROCESS | 1669 | __start_cc_report 02:02:03 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,418 02:02:03 INFO - PROCESS | 1669 | __end_cc_report 02:02:03 INFO - PROCESS | 1669 | __startTimestamp1464080523762__endTimestamp 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | ------- Summary: start ------- 02:02:03 INFO - PROCESS | 1669 | Number of tests: 6 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5776.32 Median:6060.61 stddev:807.35 (13.3%) stddev-sans-first:789.86 02:02:03 INFO - PROCESS | 1669 | Values: 3502.0 3527.7 3548.2 3573.9 3583.3 5783.8 5789.6 5904.4 5921.9 5951.1 5716.6 5721.9 5787.5 5869.9 5928.0 5238.1 5277.9 5388.6 5419.2 5435.7 404.3 5314.8 5337.8 5364.6 5382.8 5306.3 5328.6 5348.9 5364.2 5368.8 4387.3 4408.0 4720.7 5309.9 5428.1 5218.6 5256.3 5321.1 5351.9 5376.8 4513.3 5705.6 5731.0 5771.8 5772.0 6202.3 6203.7 6279.6 6294.1 6307.4 6217.0 6246.8 6296.1 6339.6 6357.1 5984.6 6023.4 6039.5 6050.4 6060.6 6248.4 6349.5 6436.7 6480.3 6504.5 6171.9 6197.5 6203.1 6210.1 6212.4 6310.1 6333.2 6382.6 6403.4 6403.8 5228.3 6157.3 6171.5 6213.0 6252.1 6236.6 6260.8 6283.3 6288.2 6297.1 5091.4 5093.0 5096.8 5100.0 5102.9 3501.7 4453.4 4510.2 4514.9 4541.9 5597.9 5777.7 5919.9 5925.4 5998.2 6340.0 6352.1 6354.0 6366.4 6417.1 5776.5 5876.3 5933.2 5938.9 6006.1 5493.5 5517.3 5539.6 5560.2 5593.9 6588.9 6599.3 6605.7 6623.8 6627.4 6208.3 6227.7 6253.5 6263.3 6329.2 6540.5 6547.1 6565.9 6578.3 6586.7 6271.1 6283.3 6318.5 6326.2 6330.8 4664.4 5201.9 6221.3 6223.6 6230.3 5937.3 6205.9 6213.9 6241.1 6261.0 6084.9 6205.7 6225.9 6237.3 6251.1 5105.7 5964.2 6125.1 6129.0 6135.7 6181.4 6241.5 6251.2 6252.5 6252.9 4773.4 5996.3 6097.2 6195.5 6219.0 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:71377.62 Median:39501.81 stddev:162021.85 (410.2%) stddev-sans-first:162389.00 02:02:03 INFO - PROCESS | 1669 | Values: 513.3 514.6 538.0 560.6 563.8 40516.0 43488.0 43507.3 43523.6 43686.0 44311.3 44817.8 44865.6 44883.8 45106.5 8468.5 9645.7 9862.9 9889.4 9899.2 9643.2 9721.9 9730.1 9761.5 9765.4 9724.9 9744.8 9791.2 9795.5 9798.4 9303.7 9677.3 9706.9 9749.5 9780.0 9644.5 9675.0 9745.2 9749.8 9751.8 40728.8 41157.2 41375.8 41426.0 41767.4 2814.5 2817.5 3476.4 3567.2 3667.7 29893.7 29937.6 30328.5 30349.8 30474.9 2730.3 2742.0 2745.6 2753.4 2843.6 519761.0 531293.0 532815.0 533828.0 534323.0 35266.3 61993.8 62344.4 62347.1 62403.7 36162.5 36429.3 37329.6 37434.8 37516.6 15087.2 15371.8 15523.3 15600.8 15628.1 13036.6 13114.4 13126.8 13132.7 13164.7 7904.3 8010.5 8010.8 8039.7 8070.9 3319.3 3336.5 3352.8 3352.8 3393.1 849.8 958.1 972.5 1006.1 1033.1 25300.0 29957.9 30114.8 30641.6 30794.7 1040.5 1045.8 1078.2 1098.0 1192.4 13742.0 15030.1 15717.1 15789.7 16073.5 683837.0 689198.0 695515.0 698011.0 698775.0 32402.5 44132.8 44617.6 44869.3 45105.3 1249.5 1423.4 1437.1 1438.5 1460.8 29977.3 30158.5 30295.8 30311.5 30781.1 45233.5 45771.8 55099.4 55467.0 55480.0 571373.0 572347.0 577381.1 581970.0 584835.0 26922.0 27172.3 27374.3 27834.4 27901.9 47321.0 47370.8 47500.3 48171.5 48384.0 33151.3 39089.2 39372.8 39450.2 40119.2 33253.0 38963.6 39016.8 39553.4 40095.2 35170.3 38050.9 38716.6 38842.0 39362.2 31089.0 32108.5 34193.0 34331.8 35006.0 25142.7 25433.7 25604.1 25783.4 25883.4 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:32555.97 Median:3123.65 stddev:91012.83 (2913.7%) stddev-sans-first:91236.09 02:02:03 INFO - PROCESS | 1669 | Values: 385.0 388.7 390.0 391.5 411.9 19773.9 20431.1 20850.6 20963.0 21226.8 20057.0 20409.8 20472.3 20824.7 20948.1 5449.2 5886.2 5891.7 5898.9 5914.6 5543.6 5726.4 5798.5 5814.9 5828.7 4822.0 5756.7 5757.4 5784.5 5795.6 5624.0 5631.6 5676.1 5719.9 5756.0 4997.7 5056.8 5079.9 5081.6 5102.4 12989.2 22530.3 23868.9 23990.3 24171.6 2052.6 2095.9 2159.4 2397.8 2462.8 9665.2 9776.6 9857.3 9905.7 9914.1 1723.1 1787.4 1802.4 1807.6 1852.7 82324.2 116725.0 117036.4 120917.0 125055.0 11346.2 11472.4 11644.1 11655.7 11790.1 14525.6 14581.9 14610.3 14638.8 14680.6 8499.7 8532.6 8684.3 8780.0 8795.4 6991.4 8500.0 8572.1 8588.2 8641.2 3784.5 4494.5 4591.1 4620.8 4665.3 1780.5 1949.8 1954.6 1957.0 1959.6 725.3 819.8 843.7 942.9 1012.6 16792.8 18413.0 18829.5 19822.1 19978.3 743.7 864.4 967.2 971.8 975.8 10133.1 10309.7 10471.7 10477.4 10558.7 535372.0 539062.0 547024.0 547731.0 547981.0 121219.0 123394.8 124570.8 125322.8 125519.4 1015.3 1031.5 1055.4 1083.2 1084.2 15812.4 17222.0 17327.1 17468.3 17581.5 48577.8 48590.0 48702.0 49524.8 50257.2 68700.0 70303.0 71054.9 73940.6 74367.6 6907.4 7267.6 7319.5 7339.9 7928.0 7994.8 8972.6 10330.0 10589.0 12841.2 9591.1 10506.8 10756.7 11078.6 11268.2 9582.5 10097.2 10673.9 10734.4 11002.6 11168.2 11189.8 11375.3 11476.9 11499.1 6135.8 7279.2 7402.1 7467.4 7635.8 10153.6 10976.6 11043.7 11130.0 11333.2 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5745.20 Median:4721.87 stddev:3258.55 (69.0%) stddev-sans-first:3252.49 02:02:03 INFO - PROCESS | 1669 | Values: 1557.4 1568.2 1608.6 1914.1 1920.8 8145.2 8653.6 8667.7 9210.4 9222.0 7516.6 8768.1 8803.2 8930.0 9028.1 2370.9 2407.3 2417.1 2418.4 2418.4 2365.4 2370.8 2393.0 2394.4 2395.4 2004.5 2381.6 2389.0 2409.7 2419.0 2355.3 2365.8 2368.7 2381.2 2388.5 2331.6 2388.1 2390.8 2394.0 2396.5 8904.2 9013.3 9025.3 9029.4 9060.4 5338.5 5463.5 5507.8 5518.0 5524.7 3568.4 3605.0 3607.8 3665.8 3666.1 994.7 1026.3 1033.4 1039.1 1052.9 10919.1 11787.7 11844.2 12008.0 12053.8 4819.8 4839.9 4918.4 4924.0 4934.7 10038.0 10048.5 10140.6 10145.4 10151.0 4383.7 4783.2 5483.8 5493.3 5495.1 4467.7 4822.5 4945.9 4963.4 4995.7 4063.6 4067.3 4116.1 4121.3 4137.0 1115.9 1309.2 1321.4 1323.9 1333.4 846.7 903.7 912.4 915.3 938.5 5796.6 5833.4 5838.3 5963.6 5989.8 428.8 435.6 438.4 442.1 442.7 3857.6 3919.2 3983.0 3991.0 4013.6 10640.1 10986.2 11003.4 11015.7 11050.4 10402.3 10820.6 10839.3 10927.9 10972.1 8288.2 8311.5 8314.0 8399.3 8421.7 5790.9 5797.5 5845.2 5886.5 5927.1 9982.8 10129.8 10152.0 10232.8 10395.5 10075.2 10192.5 10305.5 10330.4 10422.1 7512.8 7529.4 7561.9 7630.3 7677.8 7471.3 8217.8 8234.2 8236.8 8246.2 7061.8 7461.7 7477.7 7528.8 7551.2 7223.9 7304.7 7327.5 7420.7 7474.9 6948.2 7002.0 7019.5 7022.5 7033.3 4769.4 4899.0 4966.1 4986.0 4998.8 4691.4 4752.4 4756.0 4794.3 4805.5 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4717.64 Median:5369.84 stddev:1473.93 (27.4%) stddev-sans-first:1440.41 02:02:03 INFO - PROCESS | 1669 | Values: 307.2 318.9 320.2 325.2 328.1 5620.6 5662.8 5678.5 5683.8 5692.7 5006.7 5553.2 5569.0 5583.9 5591.2 3968.0 4104.4 4195.6 4211.2 4232.7 3714.5 3953.5 3996.0 4194.2 4214.6 3697.9 3918.5 3974.4 4195.9 4208.2 3728.0 3901.1 4160.4 4193.6 4208.2 3270.4 3325.9 3589.8 3612.6 3613.5 5343.8 5375.8 5389.0 5401.2 5442.4 4014.6 4063.0 4590.5 4780.6 4878.2 5413.2 5627.2 5674.7 5929.3 5962.8 3693.3 3702.5 3878.8 3984.8 3994.0 5122.4 5754.0 5845.6 5937.3 6078.2 3976.4 3990.7 4011.7 4021.8 4038.8 3518.6 4078.7 4086.2 4087.5 4092.8 4193.5 4750.3 4764.0 4796.4 4801.2 5223.6 5363.6 5924.9 5970.5 5986.2 1243.9 1371.6 1399.3 1399.6 1401.4 843.3 857.5 861.3 862.4 905.7 4769.6 5276.2 5484.8 5487.0 5510.1 5988.1 5998.4 6035.0 6036.0 6084.6 5090.5 5149.4 5206.4 5416.1 5541.0 2810.6 2965.5 3168.7 3204.9 3244.0 5745.5 5874.1 5974.3 6178.8 6280.8 5894.7 5959.5 6232.7 6235.2 6267.3 5829.6 6000.5 6023.6 6311.5 6340.9 4927.4 5820.4 6048.6 6050.3 6060.5 4950.4 5331.4 5935.4 6006.2 6011.6 5363.9 6085.9 6287.3 6307.2 6355.8 3595.0 3950.1 3956.5 3971.9 3973.5 5655.3 5743.1 6024.8 6103.0 6131.7 4482.7 5234.3 5601.5 5685.8 5852.3 5182.6 5210.1 5777.4 5823.9 5846.2 4977.7 5475.8 5580.6 5765.9 5790.2 5535.6 5879.0 5906.8 5911.6 5928.7 5009.9 5150.9 5575.1 5598.7 5802.9 02:02:03 INFO - PROCESS | 1669 | 02:02:03 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18210.13 Median:603.85 stddev:26241.29 (4345.7%) stddev-sans-first:26281.78 02:02:03 INFO - PROCESS | 1669 | Values: 650.4 658.6 659.9 717.3 761.8 14644.4 14811.0 15392.7 15415.5 15431.9 14388.1 14443.3 14444.9 14461.9 14474.2 706.6 864.9 865.1 887.7 890.8 850.1 877.8 889.7 892.2 893.3 895.1 944.5 948.0 950.2 951.4 891.4 936.3 941.1 941.3 943.4 658.4 660.2 666.9 687.2 701.7 14900.4 15055.5 15144.4 15191.2 15376.2 12667.6 12755.7 12769.6 12795.1 12929.7 9952.4 10007.1 10081.3 10299.1 10470.4 3470.3 3504.0 3509.7 3516.1 3518.1 76506.9 82766.0 82864.0 83262.0 83898.0 9238.9 9408.9 9417.8 9472.0 9698.7 21374.6 21405.6 21745.9 21966.5 22002.7 7254.6 7322.1 7365.4 7425.8 7580.3 3833.7 3899.3 3920.2 3925.3 3930.5 3418.0 3460.4 3482.9 3520.5 3520.9 581.3 618.0 631.0 631.8 642.9 517.3 582.3 593.8 599.4 616.2 6507.5 8059.9 8110.4 8124.9 8252.8 536.9 565.4 567.1 587.7 608.3 3903.1 3906.4 3931.5 3999.0 4068.9 94006.0 97362.5 99509.0 99988.0 101993.0 78658.6 81860.0 83672.7 84310.6 88563.0 62940.0 63128.7 67119.0 74540.6 76879.6 6877.0 7445.6 7706.9 7835.0 7858.5 45623.3 45943.2 46251.3 46838.8 47653.0 55944.7 57061.7 63839.0 64134.0 65558.4 10391.2 10485.9 10511.5 10554.0 10874.9 10952.0 11177.4 11346.0 11420.3 11550.2 10603.9 10621.5 10831.4 10923.3 10928.9 10666.9 10893.2 10901.9 10949.8 11012.7 10302.9 10436.2 10574.6 10608.3 11000.8 9048.9 9214.1 9298.5 9317.5 9452.3 7494.1 8469.7 8845.2 8876.5 8947.7 02:02:03 INFO - PROCESS | 1669 | -------- Summary: end -------- 02:02:03 INFO - PROCESS | 1669 | 02:02:04 INFO - TEST-INFO | 1669: exit 0 02:02:04 INFO - TEST-OK | dromaeo_css | took 1111987ms 02:02:04 INFO - TEST-START | kraken 02:02:04 INFO - operating with platform_type : mac_ 02:02:04 INFO - Initialising browser for kraken test... 02:02:04 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpBVXC7T/profile http://localhost:49280/getInfo.html) 02:02:07 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 02:02:07 INFO - PROCESS | 1678 | colorDepth:24 02:02:07 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 02:02:07 INFO - PROCESS | 1678 | __metrics 02:02:07 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:02:07 INFO - TEST-INFO | 1678: exit 0 02:02:07 INFO - Browser initialized. 02:02:07 INFO - Running cycle 1/1 for kraken test... 02:02:07 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/T/tmpBVXC7T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:02:19 INFO - PROCESS | 1681 | RSS: Main: 249827328 02:02:19 INFO - PROCESS | 1681 | 02:02:22 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 02:02:23 INFO - PROCESS | 1681 | RSS: Main: 261672960 02:02:23 INFO - PROCESS | 1681 | 02:02:26 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 02:02:26 INFO - PROCESS | 1681 | RSS: Main: 266448896 02:02:26 INFO - PROCESS | 1681 | 02:02:30 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 02:02:30 INFO - PROCESS | 1681 | RSS: Main: 266678272 02:02:30 INFO - PROCESS | 1681 | 02:02:32 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 02:02:33 INFO - PROCESS | 1681 | RSS: Main: 267288576 02:02:33 INFO - PROCESS | 1681 | 02:02:35 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:02:36 INFO - PROCESS | 1681 | RSS: Main: 267976704 02:02:36 INFO - PROCESS | 1681 | 02:02:39 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 02:02:39 INFO - PROCESS | 1681 | RSS: Main: 268517376 02:02:39 INFO - PROCESS | 1681 | 02:02:43 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 02:02:43 INFO - PROCESS | 1681 | RSS: Main: 268967936 02:02:43 INFO - PROCESS | 1681 | 02:02:46 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 02:02:47 INFO - PROCESS | 1681 | RSS: Main: 269160448 02:02:47 INFO - PROCESS | 1681 | 02:02:49 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:02:49 INFO - PROCESS | 1681 | RSS: Main: 268238848 02:02:49 INFO - PROCESS | 1681 | 02:02:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:02:52 INFO - PROCESS | 1681 | RSS: Main: 268324864 02:02:52 INFO - PROCESS | 1681 | 02:02:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:02:55 INFO - PROCESS | 1681 | RSS: Main: 268656640 02:02:55 INFO - PROCESS | 1681 | 02:02:58 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:02:59 INFO - PROCESS | 1681 | RSS: Main: 268787712 02:02:59 INFO - PROCESS | 1681 | 02:03:02 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:03:02 INFO - PROCESS | 1681 | RSS: Main: 268738560 02:03:02 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 02:03:04 INFO - PROCESS | 1681 | RSS: Main: 270630912 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | __start_tp_report 02:03:04 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 02:03:04 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 02:03:04 INFO - PROCESS | 1681 | |i|pagename|runs| 02:03:04 INFO - PROCESS | 1681 | |0;ai-astar;118;106;106;116;114;116;117;117;113;115 02:03:04 INFO - PROCESS | 1681 | |1;audio-beat-detection;154;126;140;197;119;143;139;122;172;110 02:03:04 INFO - PROCESS | 1681 | |2;audio-dft;197;199;195;206;230;197;197;242;280;217 02:03:04 INFO - PROCESS | 1681 | |3;audio-fft;68;82;75;172;57;58;60;68;124;60 02:03:04 INFO - PROCESS | 1681 | |4;audio-oscillator;66;101;205;114;82;80;124;80;81;133 02:03:04 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;89;85;87;85;108;98;89;85;88;85 02:03:04 INFO - PROCESS | 1681 | |6;imaging-darkroom;94;94;96;94;94;94;95;129;150;150 02:03:04 INFO - PROCESS | 1681 | |7;imaging-desaturate;91;91;91;91;123;84;141;147;101;105 02:03:04 INFO - PROCESS | 1681 | |8;json-parse-financial;56;59;59;61;60;57;58;120;61;57 02:03:04 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;68;67;72;69;91;99;109;101;72;69 02:03:04 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;110;76;108;111;107;108;94;86;86;83 02:03:04 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;156;149;146;123;142;148;149;125;252;138 02:03:04 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;169;165;163;164;163;164;166;164;165;165 02:03:04 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;65;76;86;90;71;63;68;71;86 02:03:04 INFO - PROCESS | 1681 | __end_tp_report 02:03:04 INFO - PROCESS | 1681 | __start_cc_report 02:03:04 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1087 02:03:04 INFO - PROCESS | 1681 | __end_cc_report 02:03:04 INFO - PROCESS | 1681 | __startTimestamp1464080584846__endTimestamp 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | ------- Summary: start ------- 02:03:04 INFO - PROCESS | 1681 | Number of tests: 14 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:113.80 Median:116.00 stddev:4.37 (3.8%) stddev-sans-first:4.36 02:03:04 INFO - PROCESS | 1681 | Values: 118.0 106.0 106.0 116.0 114.0 116.0 117.0 117.0 113.0 115.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:141.50 stddev:26.44 (18.7%) stddev-sans-first:27.70 02:03:04 INFO - PROCESS | 1681 | Values: 154.0 126.0 140.0 197.0 119.0 143.0 139.0 122.0 172.0 110.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:216.00 Median:211.50 stddev:27.65 (13.1%) stddev-sans-first:28.46 02:03:04 INFO - PROCESS | 1681 | Values: 197.0 199.0 195.0 206.0 230.0 197.0 197.0 242.0 280.0 217.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:82.40 Median:64.00 stddev:37.23 (58.2%) stddev-sans-first:39.12 02:03:04 INFO - PROCESS | 1681 | Values: 68.0 82.0 75.0 172.0 57.0 58.0 60.0 68.0 124.0 60.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:106.60 Median:73.00 stddev:40.95 (56.1%) stddev-sans-first:40.71 02:03:04 INFO - PROCESS | 1681 | Values: 66.0 101.0 205.0 114.0 82.0 80.0 124.0 80.0 81.0 133.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:87.50 stddev:7.48 (8.5%) stddev-sans-first:7.92 02:03:04 INFO - PROCESS | 1681 | Values: 89.0 85.0 87.0 85.0 108.0 98.0 89.0 85.0 88.0 85.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:109.00 Median:94.00 stddev:24.16 (25.7%) stddev-sans-first:25.00 02:03:04 INFO - PROCESS | 1681 | Values: 94.0 94.0 96.0 94.0 94.0 94.0 95.0 129.0 150.0 150.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:106.50 Median:87.50 stddev:22.61 (25.8%) stddev-sans-first:23.28 02:03:04 INFO - PROCESS | 1681 | Values: 91.0 91.0 91.0 91.0 123.0 84.0 141.0 147.0 101.0 105.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:59.00 stddev:19.47 (33.0%) stddev-sans-first:20.39 02:03:04 INFO - PROCESS | 1681 | Values: 56.0 59.0 59.0 61.0 60.0 57.0 58.0 120.0 61.0 57.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:81.70 Median:70.50 stddev:16.39 (23.3%) stddev-sans-first:16.62 02:03:04 INFO - PROCESS | 1681 | Values: 68.0 67.0 72.0 69.0 91.0 99.0 109.0 101.0 72.0 69.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:79.50 stddev:13.31 (16.7%) stddev-sans-first:13.25 02:03:04 INFO - PROCESS | 1681 | Values: 110.0 76.0 108.0 111.0 107.0 108.0 94.0 86.0 86.0 83.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:148.50 stddev:36.43 (24.5%) stddev-sans-first:38.62 02:03:04 INFO - PROCESS | 1681 | Values: 156.0 149.0 146.0 123.0 142.0 148.0 149.0 125.0 252.0 138.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:165.00 stddev:1.75 (1.1%) stddev-sans-first:1.00 02:03:04 INFO - PROCESS | 1681 | Values: 169.0 165.0 163.0 164.0 163.0 164.0 166.0 164.0 165.0 165.0 02:03:04 INFO - PROCESS | 1681 | 02:03:04 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:73.50 stddev:9.65 (13.1%) stddev-sans-first:9.96 02:03:04 INFO - PROCESS | 1681 | Values: 68.0 65.0 76.0 86.0 90.0 71.0 63.0 68.0 71.0 86.0 02:03:04 INFO - PROCESS | 1681 | -------- Summary: end -------- 02:03:04 INFO - PROCESS | 1681 | 02:03:05 INFO - TEST-INFO | 1681: exit 0 02:03:05 INFO - TEST-OK | kraken | took 61301ms 02:03:05 INFO - SUITE-END | took 1173s 02:03:05 INFO - Completed test suite (00:19:33) 02:03:05 INFO - javascript benchmark 02:03:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7093.963337185403, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3501.982490087549, 3527.7001454876336, 3548.237129017261, 3573.9463908041384, 3583.2833433313344, 5783.76864925403, 5789.581552519633, 5904.350521442649, 5921.940780592193, 5951.137085122666, 5716.599838011341, 5721.942780572193, 5787.50806181474, 5869.882602347961, 5928.02187639039, 5238.083335416296, 5277.947220527803, 5388.568914486851, 5419.214213938996, 5435.673859568419, 404.2876830741948, 5314.813981510648, 5337.839864804062, 5364.597655175858, 5382.811601593945, 5306.310179676637, 5328.573714102882, 5348.946510534884, 5364.22218778275, 5368.785248590071, 4387.276099443588, 4407.955920440787, 4720.716756994591, 5309.946900531023, 5428.104362780136, 5218.634695571312, 5256.26412302278, 5321.122014867542, 5351.892962140774, 5376.8118115865755, 4513.300438432048, 5705.6006079574445, 5730.971345143247, 5771.797987070474, 5771.971140144315, 6202.34875338092, 6203.689815509207, 6279.591826531307, 6294.118823364733, 6307.400796924285, 6217.0, 6246.750129994794, 6296.087067375251, 6339.619622822645, 6357.141785858896, 5984.551158663117, 6023.427774361428, 6039.4564489195745, 6050.42520960508, 6060.606060606046, 6248.4064013918605, 6349.52378571609, 6436.678166091677, 6480.2547707013355, 6504.5446818723185, 6171.907421388682, 6197.4732147767945, 6203.100550420254, 6210.130581718563, 6212.409821066992, 6310.148130002391, 6333.20834895638, 6382.5532212744665, 6403.391677790602, 6403.775867844696, 5228.320318358665, 6157.291911430153, 6171.537134714825, 6213.0, 6252.062190671345, 6236.563440559208, 6260.843478912972, 6283.3088360280335, 6288.213973253343, 6297.086922396227, 5091.439941606421, 5093.0, 5096.821611243588, 5100.030994111181, 5102.872428189325, 3501.7373696972827, 4453.398791163151, 4510.233260345749, 4514.887127821765, 4541.863744087683, 5597.860053498695, 5777.653340799481, 5919.881602368015, 5925.4074592541265, 5998.220231369894, 6340.0, 6352.078948552434, 6353.96823015882, 6366.395192456709, 6417.133686952202, 5776.508996735324, 5876.265466816648, 5933.169356290123, 5938.881222375615, 6006.12911127884, 5493.533049690741, 5517.2551705519245, 5539.612227144061, 5560.165975103767, 5593.8601534961135, 6588.934110658928, 6599.274079851117, 6605.70274337656, 6623.768168114189, 6627.403533681992, 6208.286047104641, 6227.657478838707, 6253.530985176039, 6263.27972283175, 6329.2404911409085, 6540.50946179029, 6547.148870646785, 6565.901511477236, 6578.276389597045, 6586.670666466562, 6271.090691849748, 6283.340249273943, 6318.462930650946, 6326.177596912372, 6330.841728956629, 4664.440267167962, 5201.947980520146, 6221.2845522765465, 6223.564350495421, 6230.345813689679, 5937.317208520903, 6205.937940620536, 6213.9067913980425, 6241.063840423791, 6261.0, 6084.90872636902, 6205.720742566505, 6225.937740622536, 6237.282712488122, 6251.093591429127, 5105.693658380509, 5964.194833697569, 6125.11336343068, 6128.9693551531955, 6135.7238924249405, 6181.381861813783, 6241.531885108726, 6251.187345645037, 6252.468540174136, 6252.9374706254175, 4773.3555969945, 5996.310424301087, 6097.237845269341, 6195.504359651127, 6218.968905155445], "value": 5728.510103547835, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.268592256035, 514.6166106250841, 538.036913924076, 560.5711630602588, 563.8252141836031, 40515.98710032247, 43488.0, 43507.34738978918, 43523.56476435235, 43686.0, 44311.335329970076, 44817.77591112048, 44865.55134448654, 44883.77558112205, 45106.54893451073, 8468.491890486559, 9645.662401815938, 9862.90137098627, 9889.357191782547, 9899.15857152143, 9643.180329671986, 9721.854172187423, 9730.075642813921, 9761.463119528424, 9765.414075155479, 9724.854127188099, 9744.805103897954, 9791.16775074116, 9795.461249631267, 9798.412095274307, 9303.720888373326, 9677.27420443466, 9706.854397184048, 9749.463779492125, 9779.951100244452, 9644.517774111266, 9674.951625241829, 9745.171660408874, 9749.756256093584, 9751.804963900753, 40728.79635601833, 41157.176856462705, 41375.75872723823, 41426.0, 41767.37348939768, 2814.535601625729, 2817.4646817104685, 3476.443768996948, 3567.23304489536, 3667.6699097081123, 29893.70106298931, 29937.550936735966, 30328.54507182394, 30349.848250758605, 30474.933377331687, 2730.344717267861, 2741.9588706169425, 2745.6156138140677, 2753.3804893898914, 2843.5734639803986, 519761.0, 531293.0, 532814.9999999962, 533828.0, 534323.0, 35266.29467410689, 61993.76012479725, 62344.37655623476, 62347.06479402813, 62403.6879815598, 36162.45756313657, 36429.27141457156, 37329.626703733156, 37434.812825935696, 37516.624833751855, 15087.24563771807, 15371.846281537266, 15523.301451434712, 15600.765988510182, 15628.140452275235, 13036.608901732963, 13114.409851556702, 13126.803097953538, 13132.671683207996, 13164.736705266032, 7904.32813210872, 8010.519368837889, 8010.759677209693, 8039.678412863537, 8070.9192908071345, 3319.286353434023, 3336.5328853960464, 3352.7820691654965, 3352.798832070084, 3393.1347506386205, 849.8442118718538, 958.1185309515263, 972.4700038479045, 1006.0844631385404, 1033.0702367868828, 25300.0, 29957.850210748806, 30114.84942575273, 30641.64120471385, 30794.69205307963, 1040.484959944817, 1045.764702941832, 1078.2344535379812, 1097.9560817567367, 1192.4097571702064, 13742.0, 15030.098194108388, 15717.135557544216, 15789.68420631581, 16073.517794466185, 683837.0, 689198.0, 695515.0, 698011.0, 698775.0, 32402.513962290584, 44132.779336103114, 44617.55382446199, 44869.32696009563, 45105.32342014872, 1249.4752204074312, 1423.4448565059636, 1437.1089924247033, 1438.518096437684, 1460.7881857130444, 29977.346968112055, 30158.54762178613, 30295.848520757256, 30311.545326819672, 30781.076567703003, 45233.547664522936, 45771.77114114408, 55099.44900551104, 55467.0, 55480.0, 571373.0, 572347.0, 577381.1130944318, 581970.0, 584835.0, 26922.0, 27172.32069198286, 27374.315642109108, 27834.44331113326, 27901.860490697418, 47321.0, 47370.763146185425, 47500.2874956869, 48171.5182848167, 48384.0, 33151.33697325992, 39089.21821563496, 39372.80313598413, 39450.2109957805, 40119.1976160481, 33253.0, 38963.61036389713, 39016.80491597524, 39553.406698898965, 40095.1980960385, 35170.29659406746, 38050.85847424582, 38716.61283387243, 38842.0, 39362.2127557453, 31089.0, 32108.518372224902, 34193.0, 34331.82834085914, 35006.0, 25142.74857251404, 25433.745662543137, 25604.103856364938, 25783.355416114, 25883.352916177246], "value": 18733.132154042047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.9730518863679, 388.7123528588844, 390.03660957435136, 391.54971782450195, 411.90732085280814, 19773.901130494345, 20431.080601372938, 20850.582988340244, 20963.0, 21226.787732122673, 20057.0, 20409.795902041013, 20472.28347007855, 20824.68762968553, 20948.057337419814, 5449.209864569645, 5886.234789477362, 5891.675957822318, 5898.88202235955, 5914.585978981451, 5543.6396634218845, 5726.427357264281, 5798.478136967672, 5814.912776308359, 5828.6502809831545, 4821.951780482185, 5756.74094665739, 5757.395473475288, 5784.479396854283, 5795.623284486495, 5624.0, 5631.57763167762, 5676.148577713335, 5719.942800572004, 5756.0, 4997.700137991732, 5056.82301119459, 5079.949200507985, 5081.644284900059, 5102.387713474407, 12989.22064676113, 22530.324090277154, 23868.88065559661, 23990.323833036375, 24171.637425438457, 2052.640787862133, 2095.9371218863307, 2159.449340418199, 2397.7602239775883, 2462.827602067856, 9665.226781857435, 9776.608935642565, 9857.260705447048, 9905.702828915144, 9914.107730304235, 1723.0953749281603, 1787.4101546489758, 1802.387188355952, 1807.5752198233404, 1852.6943054396008, 82324.17675823225, 116725.0, 117036.41481792621, 120917.0, 125055.0, 11346.205765596495, 11472.369019703829, 11644.068474522019, 11655.650330490098, 11790.056795456343, 14525.636859078608, 14581.854181458262, 14610.26948652563, 14638.780418293734, 14680.55958321252, 8499.74500764978, 8532.57337133141, 8684.305255579538, 8779.956100219586, 8795.428297160792, 6991.440684745208, 8500.0, 8572.057073721886, 8588.227059564544, 8641.228548147741, 3784.4701741756176, 4494.4606647202545, 4591.104734576726, 4620.792064357045, 4665.2535594304745, 1780.5370603643146, 1949.8440124789984, 1954.638391897475, 1956.9510762230773, 1959.617874514456, 725.2747252747253, 819.8155415031689, 843.7468759372164, 942.9198518126036, 1012.5595366015807, 16792.824502284722, 18413.0, 18829.52926176829, 19822.108005139802, 19978.30075947335, 743.6876511864922, 864.4035615425337, 967.1537404770826, 971.8299297622888, 975.8487434447673, 10133.08802207805, 10309.74225644365, 10471.738206544896, 10477.42374169428, 10558.683239502674, 535372.0, 539062.0, 547024.0, 547731.0, 547981.0, 121219.0, 123394.76605233832, 124570.75429245591, 125322.79707767967, 125519.3724031374, 1015.253788465492, 1031.5306535526317, 1055.4353420919772, 1083.2254937719567, 1084.2301965604352, 15812.367505299659, 17222.0, 17327.133643318037, 17468.30126794914, 17581.472555822835, 48577.75711121422, 48590.0, 48702.0, 49524.75237623789, 50257.24614130864, 68699.96950045653, 70303.0, 71054.93417598844, 73940.63029684818, 74367.62816185884, 6907.412869905913, 7267.563946163247, 7319.45104117204, 7339.8532029360185, 7927.960360198162, 7994.760157195294, 8972.619695994661, 10330.0, 10589.0, 12841.229526228457, 9591.088846559565, 10506.842397363893, 10756.731081723019, 11078.556857725924, 11268.211225214156, 9582.472963987047, 10097.242706796873, 10673.89326106729, 10734.409607471664, 11002.614908478165, 11168.21822472419, 11189.832152517882, 11375.31748095117, 11476.942615286871, 11499.137564682514, 6135.815925522242, 7279.162896266997, 7402.111746590228, 7467.402607791472, 7635.770926872203, 10153.593856245667, 10976.615818446317, 11043.723906902393, 11130.0, 11333.150013748836], "value": 9160.119805977405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.3692654474935, 1568.215892053973, 1608.5817687401275, 1914.0716752375104, 1920.8463322934167, 8145.226203510657, 8653.61058752356, 8667.653293868245, 9210.40132391394, 9221.953890230548, 7516.586587737672, 8768.123187681229, 8803.20771130598, 8929.973053098896, 9028.14232647899, 2370.893309801063, 2407.277816655005, 2417.0694282701197, 2418.3712234818986, 2418.407490164912, 2365.373176108324, 2370.8221883358733, 2393.0, 2394.449276666368, 2395.4370722880108, 2004.4587961250454, 2381.583222935988, 2388.9641655375094, 2409.650600662903, 2418.9516209675885, 2355.281639100074, 2365.775251351119, 2368.7039120109985, 2381.2022972304385, 2388.5461762265113, 2331.603627383349, 2388.1402695029774, 2390.8445951013127, 2394.042383046778, 2396.544656515274, 8904.243139333164, 9013.278937684969, 9025.323100767402, 9029.367944243906, 9060.365774395796, 5338.519533241988, 5463.453654634544, 5507.834764957018, 5517.972410137923, 5524.696141712204, 3568.429051351797, 3604.981975090108, 3607.783532988029, 3665.816709164558, 3666.138457462494, 994.6568433890296, 1026.343140390151, 1033.390299723167, 1039.0752230514847, 1052.8631277933896, 10919.12646988239, 11787.705307367298, 11844.170908036442, 12008.0, 12053.819192712028, 4819.759012049383, 4839.879003024953, 4918.385201849697, 4923.950760492421, 4934.679245849008, 10038.0, 10048.497575121215, 10140.645077422403, 10145.391276523433, 10151.035651613085, 4383.736975781463, 4783.234682450791, 5483.75323110461, 5493.258410114663, 5495.093309603871, 4467.7319360838455, 4822.517748225149, 4945.9258111128365, 4963.354763880745, 4995.725235112031, 4063.605962815266, 4067.3085575452246, 4116.094459218967, 4121.278776214151, 4137.048478849822, 1115.8828323026128, 1309.18830325199, 1321.4251800466827, 1323.9404226809816, 1333.4399552188104, 846.699421705298, 903.7198468474794, 912.3750231091724, 915.3363811236933, 938.4979036215592, 5796.623219490804, 5833.445822646841, 5838.328592211865, 5963.5825492216, 5989.790357337472, 428.79846472158033, 435.61012893460173, 438.39900246908894, 442.1267995708514, 442.70560077548714, 3857.5756666766633, 3919.235749028912, 3982.9601703983167, 3990.980045099756, 4013.618706222904, 10640.148788096934, 10986.176036797273, 11003.394813285186, 11015.669529914116, 11050.447477626087, 10402.323848949945, 10820.621278255223, 10839.349639021684, 10927.945360273148, 10972.067374273276, 8288.21261980111, 8311.501309921425, 8314.043884953307, 8399.328053755808, 8421.747347579583, 5790.884182316414, 5797.5361971041375, 5845.240118784531, 5886.470217680429, 5927.110933360031, 9982.750431239278, 10129.797404052026, 10152.03555662211, 10232.846507302545, 10395.480225988822, 10075.244356673133, 10192.541335640062, 10305.53625086888, 10330.431826249629, 10422.062014418738, 7512.849743005, 7529.397648188241, 7561.886571701319, 7630.313271805564, 7677.808054798675, 7471.290227428385, 8217.79455513617, 8234.217749313806, 8236.83526329458, 8246.216609422096, 7061.752838650623, 7461.701531938662, 7477.738279160203, 7528.811779705551, 7551.207123251979, 7223.927760722536, 7304.744333948286, 7327.523710958771, 7420.6660700269695, 7474.92525074764, 6948.166220053626, 7002.0, 7019.473539484661, 7022.473314501331, 7033.29667033313, 4769.42766867978, 4898.951010489992, 4966.106100901728, 4985.9501404985485, 4998.750062496933, 4691.390119284471, 4752.358431611827, 4756.0, 4794.280857871207, 4805.495422980537], "value": 4528.47125777838, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.24571177758605, 318.9473736833423, 320.2314445331204, 325.2064961493956, 328.128817988241, 5620.606557540974, 5662.773489060438, 5678.488935995754, 5683.829485115442, 5692.743826527806, 5006.749662516869, 5553.222548843165, 5569.0, 5583.916241256385, 5591.161325801132, 3968.0, 4104.425380446725, 4195.643370313527, 4211.22092412903, 4232.703710740249, 3714.4985426967282, 3953.4581513209873, 3995.9600403996023, 4194.161167766443, 4214.557471465498, 3697.907552311187, 3918.471006414126, 3974.3641017437217, 4195.916081678364, 4208.158368326332, 3727.9627203727887, 3901.122247494291, 4160.375943608453, 4193.559676233982, 4208.200441916026, 3270.3698894227387, 3325.8835940741956, 3589.7974178650184, 3612.584552776438, 3613.4579813027995, 5343.759530821084, 5375.75809088592, 5388.976094542064, 5401.189821526763, 5442.4489020628935, 4014.6386825185873, 4063.0, 4590.517995610447, 4780.641451891122, 4878.243872199815, 5413.188021796723, 5627.240322556484, 5674.716264186774, 5929.258842644669, 5962.791302304442, 3693.2613477304376, 3702.5186725725484, 3878.8448462061483, 3984.8406063757416, 3993.980030099831, 5122.436531981479, 5754.043625167463, 5845.5908086433965, 5937.346891841894, 6078.240219972459, 3976.4234186042863, 3990.6607938324987, 4011.6502720228355, 4021.8391264349684, 4038.7778672172726, 3518.612952575215, 4078.6533144683035, 4086.1827634473225, 4087.529934057562, 4092.815823287959, 4193.496780386373, 4750.311204875343, 4763.95236047642, 4796.35249241348, 4801.207800712843, 5223.582113430916, 5363.597730170253, 5924.940750592526, 5970.49250813677, 5986.2217911671205, 1243.9426487485746, 1371.5953793630704, 1399.2514005007272, 1399.6221020324456, 1401.4114072089747, 843.2958479669545, 857.4512312120248, 861.3152543727751, 862.4260724178399, 905.6875377994713, 4769.618430525549, 5276.15581506957, 5484.780608775684, 5487.0, 5510.1459273812625, 5988.053882488209, 5998.370171131968, 6034.969825150934, 6035.969820150959, 6084.574079814459, 5090.516400941979, 5149.382074151051, 5206.427292997767, 5416.106342453451, 5541.0, 2810.564362523812, 2965.4513914925806, 3168.651448340681, 3204.903852884417, 3244.0, 5745.511631511285, 5874.089516124922, 5974.312954010257, 6178.845528861814, 6280.806646737105, 5894.705264736746, 5959.523238140939, 6232.688365581703, 6235.158253635792, 6267.310595834455, 5829.621074630136, 6000.459958603747, 6023.608465449732, 6311.526635502264, 6340.9365906340345, 4927.408710954708, 5820.447057529528, 6048.60684055535, 6050.304215015153, 6060.484858786876, 4950.41586692255, 5331.413544510023, 5935.379418554765, 6006.219191505251, 6011.617328014669, 5363.919541206807, 6085.878282434238, 6287.30839607652, 6307.243130824147, 6355.841103972438, 3595.0113718727557, 3950.0644684105155, 3956.4658771065538, 3971.900702482461, 3973.5033120859894, 5655.264815573949, 5743.0811070228565, 6024.819255422344, 6102.986014615255, 6131.693415329305, 4482.719003351856, 5234.345706786652, 5601.467860553241, 5685.829425117253, 5852.3269823969085, 5182.611304152279, 5210.114280572236, 5777.3933736957015, 5823.854403639943, 5846.210761547307, 4977.651564390458, 5475.808346707846, 5580.581456390868, 5765.855853603525, 5790.218320526675, 5535.557155427637, 5878.970605146947, 5906.8440019391755, 5911.586188966903, 5928.666050138684, 5009.924851127309, 5150.9227361588855, 5575.135853942646, 5598.692071936082, 5802.941970580409], "value": 4242.888733988956, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.4438704907304, 658.6278752504834, 659.9175103112111, 717.3292971072048, 761.8438220164867, 14644.414223431064, 14810.969979786905, 15392.692146157071, 15415.537533873987, 15431.92284038581, 14388.136711797273, 14443.277836108178, 14444.855551444482, 14461.927690361534, 14474.203918784462, 706.5725236232082, 864.9048604653483, 865.1046167216932, 887.7336798960318, 890.781758469176, 850.0776657326808, 877.784942689042, 889.6530353162271, 892.1747383670112, 893.3210759822549, 895.1496078725205, 944.5466176235411, 948.0424770981324, 950.1686024728363, 951.4386511957944, 891.3671293381707, 936.283742936654, 941.1153515695224, 941.2799208605422, 943.3820847345, 658.3613894522306, 660.1615947746383, 666.9299723529008, 687.22000529399, 701.6772284749021, 14900.403983840633, 15055.54833355001, 15144.394224231017, 15191.164485953268, 15376.154311512862, 12667.556635517805, 12755.744885102338, 12769.553065642658, 12795.104342696015, 12929.676758081028, 9952.353097048668, 10007.09936105754, 10081.294309398345, 10299.124574411184, 10470.42412667303, 3470.305938812248, 3504.036389992747, 3509.736769742254, 3516.1033936346325, 3518.1204698825295, 76506.91613964006, 82766.0, 82864.0, 83262.0, 83898.0, 9238.90761092394, 9408.858867116998, 9417.811643767087, 9472.005439428898, 9698.660546880825, 21374.572508549743, 21405.571888562452, 21745.891270543547, 21966.45083872884, 22002.669959950614, 7254.5647261164495, 7322.0847394075745, 7365.410767138616, 7425.8143546411775, 7580.3177714004905, 3833.654971052563, 3899.337112690849, 3920.157166209279, 3925.3130702127014, 3930.508686414198, 3417.9829100854336, 3460.3598334308235, 3482.8606855725993, 3520.471929210639, 3520.859165633346, 581.3401788969466, 617.9938200618026, 631.0376675569793, 631.8388810853295, 642.8971364581645, 517.301642782241, 582.2692520886261, 593.7981086430634, 599.4245524296637, 616.2081724983375, 6507.544471886922, 8059.879101813477, 8110.43226974118, 8124.918750812534, 8252.752417427366, 536.9087255166553, 565.4204440448573, 567.1322875999728, 587.670904293597, 608.2700759089022, 3903.1413089120347, 3906.4140378942816, 3931.4692516510477, 3998.980005099956, 4068.9389659155136, 94006.0, 97362.53956190663, 99509.0, 99988.0, 101993.0, 78658.6067069661, 81860.0, 83672.74490882641, 84310.57844710737, 88563.0, 62940.0, 63128.73742525124, 67118.99321510181, 74540.62729686426, 76879.61560192163, 6877.0, 7445.585338785613, 7706.922930770621, 7835.0, 7858.528488290721, 45623.31565026593, 45943.162273510025, 46251.306230407245, 46838.76580617075, 47653.0, 55944.72027639836, 57061.71469142628, 63839.0, 64134.0, 65558.36104097436, 10391.220658450799, 10485.9475702621, 10511.474426278503, 10553.997370249814, 10874.945625271823, 10952.014318711354, 11177.396841796859, 11346.0355869752, 11420.31478111316, 11550.249233799776, 10603.89396106029, 10621.468926553487, 10831.350118992885, 10923.344599324066, 10928.890711092787, 10666.89333106659, 10893.237473376788, 10901.945490272497, 10949.83575246356, 11012.724681883017, 10302.896971030194, 10436.16510679159, 10574.577016919236, 10608.310459820086, 11000.834987475035, 9048.864267035868, 9214.124658157465, 9298.488583127722, 9317.534123293945, 9452.291078169028, 7494.100707915085, 8469.745907622535, 8845.159709827556, 8876.467411955304, 8947.731568052968], "value": 6747.22888679719, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 106.0, 106.0, 116.0, 114.0, 116.0, 117.0, 117.0, 113.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 140.0, 197.0, 119.0, 143.0, 139.0, 122.0, 172.0, 110.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 195.0, 206.0, 230.0, 197.0, 197.0, 242.0, 280.0, 217.0], "value": 216.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 82.0, 75.0, 172.0, 57.0, 58.0, 60.0, 68.0, 124.0, 60.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 101.0, 205.0, 114.0, 82.0, 80.0, 124.0, 80.0, 81.0, 133.0], "value": 106.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 87.0, 85.0, 108.0, 98.0, 89.0, 85.0, 88.0, 85.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 95.0, 129.0, 150.0, 150.0], "value": 109.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 91.0, 123.0, 84.0, 141.0, 147.0, 101.0, 105.0], "value": 106.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 59.0, 61.0, 60.0, 57.0, 58.0, 120.0, 61.0, 57.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 69.0, 91.0, 99.0, 109.0, 101.0, 72.0, 69.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 76.0, 108.0, 111.0, 107.0, 108.0, 94.0, 86.0, 86.0, 83.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 149.0, 146.0, 123.0, 142.0, 148.0, 149.0, 125.0, 252.0, 138.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 163.0, 164.0, 163.0, 164.0, 166.0, 164.0, 165.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 76.0, 86.0, 90.0, 71.0, 63.0, 68.0, 71.0, 86.0], "value": 74.4, "unit": "score"}]}]} 02:03:06 INFO - Return code: 0 02:03:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:03:06 ERROR - # TBPL SUCCESS # 02:03:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:03:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:03:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:03:06 INFO - 2016-05-24 02:03:06.158 screenresolution[1686:10233] starting screenresolution argv=screenresolution get 02:03:06 INFO - 2016-05-24 02:03:06.161 screenresolution[1686:10233] Display 0: 1600x1200x32@60 02:03:06 INFO - 2016-05-24 02:03:06.166 screenresolution[1687:10236] starting screenresolution argv=screenresolution list 02:03:06 INFO - Available Modes on Display 0 02:03:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:03:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:03:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:03:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:03:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:03:06 INFO - Intel Iris: 02:03:06 INFO - Chipset Model: Intel Iris 02:03:06 INFO - Type: GPU 02:03:06 INFO - Bus: Built-In 02:03:06 INFO - VRAM (Dynamic, Max): 1536 MB 02:03:06 INFO - Vendor: Intel (0x8086) 02:03:06 INFO - Device ID: 0x0a2e 02:03:06 INFO - Revision ID: 0x0009 02:03:06 INFO - Displays: 02:03:06 INFO - Display: 02:03:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:03:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:03:06 INFO - Display Serial Number: 1600x1200 60 02:03:06 INFO - Main Display: Yes 02:03:06 INFO - Mirror: Off 02:03:06 INFO - Online: Yes 02:03:06 INFO - Rotation: Supported 02:03:06 INFO - Return code: 0 02:03:06 INFO - Running post-action listener: _resource_record_post_action 02:03:06 INFO - ##### 02:03:06 INFO - ##### Finished run-tests step (success) 02:03:06 INFO - ##### 02:03:06 INFO - Running post-run listener: _resource_record_post_run 02:03:06 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 36877824; Write bytes: 697760256; Read time: 243; Write time: 1904 02:03:06 INFO - TinderboxPrint: CPU usage
54.1% 02:03:06 INFO - TinderboxPrint: I/O read bytes / time
36,877,824 / 243 02:03:06 INFO - TinderboxPrint: I/O write bytes / time
697,760,256 / 1,904 02:03:06 INFO - TinderboxPrint: CPU idle
2,218.3 (45.9%) 02:03:06 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 02:03:06 INFO - TinderboxPrint: CPU user
1,650.2 (34.1%) 02:03:06 INFO - TinderboxPrint: Swap in / out
433,004,544 / 0 02:03:06 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402563584; Write bytes: 399599616; Read time: 29402; Write time: 795 02:03:06 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 29728256; Write bytes: 297144832; Read time: 181; Write time: 1104 02:03:06 INFO - Running post-run listener: _upload_blobber_files 02:03:06 INFO - Blob upload gear active. 02:03:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:03:06 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 02:03:06 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'] 02:03:06 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 02:03:07 INFO - (blobuploader) - INFO - Open directory for files ... 02:03:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:03:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:03:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:08 INFO - (blobuploader) - INFO - Done attempting. 02:03:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:03:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:03:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:09 INFO - (blobuploader) - INFO - Done attempting. 02:03:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:03:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:03:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:11 INFO - (blobuploader) - INFO - Done attempting. 02:03:11 INFO - (blobuploader) - INFO - Iteration through files over. 02:03:11 INFO - Return code: 0 02:03:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:03:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:03:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbe475a4d4b95a2fc1621fb516d4c887312e1db1010668864b5a0d9f2f737dbcb63638c1a4afa75aeca79337f3bdb9a39cbc620fef28b92bec68106af788869c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3156a70556912421cfa93c4d7d3df49f53dde9d33da9a11fb37115770ac57dd6228e81e6c36c4b721abe44b26ab84afe2e7b3ae15978eca2d71802466c325e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92214c62afab24dbb8df5caf678b26a09202bab1293115bc69d8021a63932700d2403d9d1192e44c0bc3287e60e426c1469ff3f757972d4f6a7cd9e07790abde"} 02:03:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:03:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:03:11 INFO - Contents: 02:03:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbe475a4d4b95a2fc1621fb516d4c887312e1db1010668864b5a0d9f2f737dbcb63638c1a4afa75aeca79337f3bdb9a39cbc620fef28b92bec68106af788869c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3156a70556912421cfa93c4d7d3df49f53dde9d33da9a11fb37115770ac57dd6228e81e6c36c4b721abe44b26ab84afe2e7b3ae15978eca2d71802466c325e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92214c62afab24dbb8df5caf678b26a09202bab1293115bc69d8021a63932700d2403d9d1192e44c0bc3287e60e426c1469ff3f757972d4f6a7cd9e07790abde"} 02:03:11 INFO - Running post-run listener: copy_logs_to_upload_dir 02:03:11 INFO - Copying logs to upload dir... 02:03:11 INFO - mkdir: /builds/slave/test/build/upload/logs 02:03:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.834585 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-24 02:03:11.884730) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:03:11.887743) ========= 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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbe475a4d4b95a2fc1621fb516d4c887312e1db1010668864b5a0d9f2f737dbcb63638c1a4afa75aeca79337f3bdb9a39cbc620fef28b92bec68106af788869c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3156a70556912421cfa93c4d7d3df49f53dde9d33da9a11fb37115770ac57dd6228e81e6c36c4b721abe44b26ab84afe2e7b3ae15978eca2d71802466c325e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92214c62afab24dbb8df5caf678b26a09202bab1293115bc69d8021a63932700d2403d9d1192e44c0bc3287e60e426c1469ff3f757972d4f6a7cd9e07790abde"} build_url:https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011074 build_url: 'https://queue.taskcluster.net/v1/task/O8EhwPJcS-i0RZcJm_PqxQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbe475a4d4b95a2fc1621fb516d4c887312e1db1010668864b5a0d9f2f737dbcb63638c1a4afa75aeca79337f3bdb9a39cbc620fef28b92bec68106af788869c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e3156a70556912421cfa93c4d7d3df49f53dde9d33da9a11fb37115770ac57dd6228e81e6c36c4b721abe44b26ab84afe2e7b3ae15978eca2d71802466c325e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/92214c62afab24dbb8df5caf678b26a09202bab1293115bc69d8021a63932700d2403d9d1192e44c0bc3287e60e426c1469ff3f757972d4f6a7cd9e07790abde"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 02:03:11.920177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:03:11.920912) ========= 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.2Xh5V2K7xE/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.Es8JwHLAgF/Listeners TMPDIR=/var/folders/w3/7fy15l1j28z1pjs9bdqr5pjm00000w/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.005181 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 02:03:11.972446) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:03:11.973074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 02:03:11.973773) ========= ========= Total master_lag: 2.38 =========