builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0333 starttime: 1473498487.41 results: success (0) buildid: 20160910001520 builduid: f595f32fdee94e8e9ecb90a03e3cbe1b revision: a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:07.409479) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:07.409995) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:07.410351) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:07.538348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:07.538662) ========= 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.mScJaB2P0S/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.MHCpOKSAGA/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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-09-10 02:08:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.71M=0.001s 2016-09-10 02:08:08 (8.71 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.483979 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:08.061185) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:08.061570) ========= 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.mScJaB2P0S/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.MHCpOKSAGA/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.020874 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:08.115348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:08.115729) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c --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 a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mScJaB2P0S/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.MHCpOKSAGA/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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-09-10 02:08:08,200 truncating revision to first 12 chars 2016-09-10 02:08:08,200 Setting DEBUG logging. 2016-09-10 02:08:08,201 attempt 1/10 2016-09-10 02:08:08,201 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a07fb64ae0a7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-10 02:08:08,820 unpacking tar archive at: mozilla-central-a07fb64ae0a7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.888248 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:09.029712) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:09.030038) ========= script_repo_revision: a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:09.030897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:09.031209) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-10 02:08:09.054760) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-10 02:08:09.055104) ========= /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.mScJaB2P0S/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.MHCpOKSAGA/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:09 INFO - MultiFileLogger online at 20160910 02:08:09 in /builds/slave/test 02:08:09 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 02:08:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:09 INFO - {'append_to_log': False, 02:08:09 INFO - 'base_work_dir': '/builds/slave/test', 02:08:09 INFO - 'blob_upload_branch': 'Firefox', 02:08:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:09 INFO - 'branch': 'Firefox', 02:08:09 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:09 INFO - 'config_files': ('talos/mac_config.py',), 02:08:09 INFO - 'default_actions': ('clobber', 02:08:09 INFO - 'read-buildbot-config', 02:08:09 INFO - 'download-and-extract', 02:08:09 INFO - 'populate-webroot', 02:08:09 INFO - 'create-virtualenv', 02:08:09 INFO - 'install', 02:08:09 INFO - 'run-tests'), 02:08:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:09 INFO - 'download_minidump_stackwalk': True, 02:08:09 INFO - 'download_symbols': 'ondemand', 02:08:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:09 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:09 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:09 INFO - 'installer_path': 'installer.exe', 02:08:09 INFO - 'log_level': 'info', 02:08:09 INFO - 'log_name': 'talos', 02:08:09 INFO - 'log_to_console': True, 02:08:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:08:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:08:09 INFO - 'opt_config_files': (), 02:08:09 INFO - 'pip_index': False, 02:08:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:09 INFO - 'cmd': ('bash', 02:08:09 INFO - '-c', 02:08:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:09 INFO - 'enabled': True, 02:08:09 INFO - 'halt_on_failure': False, 02:08:09 INFO - 'name': 'check_screen_resolution'},), 02:08:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:09 INFO - 'cmd': ('bash', 02:08:09 INFO - '-c', 02:08:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:09 INFO - 'enabled': True, 02:08:09 INFO - 'halt_on_failure': False, 02:08:09 INFO - 'name': 'check_screen_resolution'},), 02:08:09 INFO - 'run_cmd_checks_enabled': True, 02:08:09 INFO - 'sps_profile': False, 02:08:09 INFO - 'sps_profile_interval': 0, 02:08:09 INFO - 'suite': 'dromaeojs-e10s', 02:08:09 INFO - 'system_bits': '32', 02:08:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:09 INFO - 'title': 't-yosemite-r7-0333', 02:08:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:09 INFO - 'use_talos_json': True, 02:08:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:09 INFO - 'work_dir': 'build'} 02:08:09 INFO - [mozharness: 2016-09-10 09:08:09.258939Z] Running clobber step. 02:08:09 INFO - Running pre-action listener: _resource_record_pre_action 02:08:09 INFO - Running main action method: clobber 02:08:09 INFO - rmtree: /builds/slave/test/build 02:08:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:10 INFO - Running post-action listener: _resource_record_post_action 02:08:10 INFO - [mozharness: 2016-09-10 09:08:10.569379Z] Finished clobber step (success) 02:08:10 INFO - [mozharness: 2016-09-10 09:08:10.569545Z] Running read-buildbot-config step. 02:08:10 INFO - Running pre-action listener: _resource_record_pre_action 02:08:10 INFO - Running main action method: read_buildbot_config 02:08:10 INFO - Using buildbot properties: 02:08:10 INFO - { 02:08:10 INFO - "project": "", 02:08:10 INFO - "product": "firefox", 02:08:10 INFO - "script_repo_revision": "production", 02:08:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:08:10 INFO - "repository": "", 02:08:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:08:10 INFO - "buildid": "20160910001520", 02:08:10 INFO - "pgo_build": "False", 02:08:10 INFO - "basedir": "/builds/slave/test", 02:08:10 INFO - "buildnumber": 112, 02:08:10 INFO - "slavename": "t-yosemite-r7-0333", 02:08:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:08:10 INFO - "platform": "yosemite_r7", 02:08:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:08:10 INFO - "branch": "mozilla-central", 02:08:10 INFO - "repo_path": "mozilla-central", 02:08:10 INFO - "revision": "a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c", 02:08:10 INFO - "stage_platform": "macosx64", 02:08:10 INFO - "builduid": "f595f32fdee94e8e9ecb90a03e3cbe1b", 02:08:10 INFO - "slavebuilddir": "test" 02:08:10 INFO - } 02:08:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:08:10 INFO - Running post-action listener: _resource_record_post_action 02:08:10 INFO - [mozharness: 2016-09-10 09:08:10.572977Z] Finished read-buildbot-config step (success) 02:08:10 INFO - [mozharness: 2016-09-10 09:08:10.573090Z] Running download-and-extract step. 02:08:10 INFO - Running pre-action listener: _resource_record_pre_action 02:08:10 INFO - Running main action method: download_and_extract 02:08:10 INFO - mkdir: /builds/slave/test/build/tests 02:08:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:10 INFO - trying https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:08:10 INFO - Downloading https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:08:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 02:08:11 INFO - Downloaded 1199 bytes. 02:08:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:08:11 INFO - Using the following test package requirements: 02:08:11 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:08:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:08:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:08:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'jsshell-mac.zip'], 02:08:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:08:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:08:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:08:11 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:08:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:08:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:08:11 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:08:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:08:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:08:12 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:08:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:08:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:08:19 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:08:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:08:26 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:27 INFO - trying https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:08:27 INFO - Downloading https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:08:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 02:08:33 INFO - Downloaded 111661853 bytes. 02:08:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:08:33 INFO - mkdir: /builds/slave/test/properties 02:08:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:33 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:33 INFO - Contents: 02:08:33 INFO - build_url:https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:08:33 INFO - Running post-action listener: _resource_record_post_action 02:08:33 INFO - Running post-action listener: set_extra_try_arguments 02:08:33 INFO - [mozharness: 2016-09-10 09:08:33.679552Z] Finished download-and-extract step (success) 02:08:33 INFO - [mozharness: 2016-09-10 09:08:33.679712Z] Running populate-webroot step. 02:08:33 INFO - Running pre-action listener: _resource_record_pre_action 02:08:33 INFO - Running main action method: populate_webroot 02:08:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:08:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:08:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:08:33 INFO - '--tppagecycles', 02:08:33 INFO - '7'], 02:08:33 INFO - 'tests': ['tsvgm']}}, 02:08:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:08:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:08:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:08:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:08:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['damp', 'tps']}, 02:08:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'tests': ['damp', 'tps']}, 02:08:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['dromaeo_dom']}, 02:08:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:08:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['basic_compositor_video']}, 02:08:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:08:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['a11yr', 02:08:33 INFO - 'ts_paint', 02:08:33 INFO - 'tpaint', 02:08:33 INFO - 'sessionrestore', 02:08:33 INFO - 'sessionrestore_no_auto_restore', 02:08:33 INFO - 'tabpaint']}, 02:08:33 INFO - 'other-e10s': {'tests': ['a11yr', 02:08:33 INFO - 'ts_paint', 02:08:33 INFO - 'tpaint', 02:08:33 INFO - 'sessionrestore', 02:08:33 INFO - 'sessionrestore_no_auto_restore', 02:08:33 INFO - 'tabpaint']}, 02:08:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:08:33 INFO - 'ts_paint', 02:08:33 INFO - 'tpaint', 02:08:33 INFO - 'sessionrestore', 02:08:33 INFO - 'sessionrestore_no_auto_restore', 02:08:33 INFO - 'tabpaint']}, 02:08:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:08:33 INFO - 'ts_paint', 02:08:33 INFO - 'tpaint', 02:08:33 INFO - 'sessionrestore', 02:08:33 INFO - 'sessionrestore_no_auto_restore', 02:08:33 INFO - 'tabpaint']}, 02:08:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['a11yr', 02:08:33 INFO - 'ts_paint', 02:08:33 INFO - 'tpaint', 02:08:33 INFO - 'sessionrestore', 02:08:33 INFO - 'sessionrestore_no_auto_restore', 02:08:33 INFO - 'tabpaint']}, 02:08:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['a11yr', 02:08:33 INFO - 'ts_paint', 02:08:33 INFO - 'tpaint', 02:08:33 INFO - 'sessionrestore', 02:08:33 INFO - 'sessionrestore_no_auto_restore', 02:08:33 INFO - 'tabpaint']}, 02:08:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['tsvgx', 02:08:33 INFO - 'tsvgr_opacity', 02:08:33 INFO - 'tart', 02:08:33 INFO - 'tscrollx', 02:08:33 INFO - 'cart']}, 02:08:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:08:33 INFO - 'tsvgr_opacity', 02:08:33 INFO - 'tart', 02:08:33 INFO - 'tscrollx', 02:08:33 INFO - 'cart']}, 02:08:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'talos_options': ['--disable-e10s'], 02:08:33 INFO - 'tests': ['tp5o']}, 02:08:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'tests': ['tp5o']}, 02:08:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'talos_options': ['--disable-e10s', 02:08:33 INFO - '--xperf_path', 02:08:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:33 INFO - 'tests': ['tp5n']}, 02:08:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:33 INFO - 'talos_options': ['--xperf_path', 02:08:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:33 INFO - 'tests': ['tp5n']}}, 02:08:33 INFO - 'talos.zip': {'path': '', 02:08:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:08:33 INFO - Running post-action listener: _resource_record_post_action 02:08:33 INFO - [mozharness: 2016-09-10 09:08:33.699615Z] Finished populate-webroot step (success) 02:08:33 INFO - [mozharness: 2016-09-10 09:08:33.699751Z] Running create-virtualenv step. 02:08:33 INFO - Running pre-action listener: _resource_record_pre_action 02:08:33 INFO - Running main action method: create_virtualenv 02:08:33 INFO - Creating virtualenv /builds/slave/test/build/venv 02:08:33 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 02:08:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:08:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:08:33 INFO - Using real prefix '/tools/python27' 02:08:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:08:34 INFO - Installing distribute.............................................................................................................................................................................................done. 02:08:37 INFO - Installing pip.................done. 02:08:37 INFO - Return code: 0 02:08:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:08:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:37 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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:37 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 02:08:37 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 02:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:37 INFO - 'HOME': '/Users/cltbld', 02:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:37 INFO - 'LOGNAME': 'cltbld', 02:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:37 INFO - 'MOZ_NO_REMOTE': '1', 02:08:37 INFO - 'NO_EM_RESTART': '1', 02:08:37 INFO - 'PAGER': '/bin/cat', 02:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:37 INFO - 'PWD': '/builds/slave/test', 02:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:37 INFO - 'SHELL': '/bin/bash', 02:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:37 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:37 INFO - 'USER': 'cltbld', 02:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:37 INFO - 'XPC_FLAGS': '0x0', 02:08:37 INFO - 'XPC_SERVICE_NAME': '0', 02:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:37 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 02:08:37 INFO - Cleaning up... 02:08:37 INFO - Return code: 0 02:08:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:08:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:37 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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:37 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 02:08:37 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 02:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:37 INFO - 'HOME': '/Users/cltbld', 02:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:37 INFO - 'LOGNAME': 'cltbld', 02:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:37 INFO - 'MOZ_NO_REMOTE': '1', 02:08:37 INFO - 'NO_EM_RESTART': '1', 02:08:37 INFO - 'PAGER': '/bin/cat', 02:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:37 INFO - 'PWD': '/builds/slave/test', 02:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:37 INFO - 'SHELL': '/bin/bash', 02:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:37 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:37 INFO - 'USER': 'cltbld', 02:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:37 INFO - 'XPC_FLAGS': '0x0', 02:08:37 INFO - 'XPC_SERVICE_NAME': '0', 02:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:37 INFO - Downloading/unpacking psutil>=3.1.1 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:08:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:41 INFO - Installing collected packages: psutil 02:08:41 INFO - Running setup.py install for psutil 02:08:41 INFO - building 'psutil._psutil_osx' extension 02:08:41 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 INFO - building 'psutil._psutil_posix' extension 02:08:42 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 02:08:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:08:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:08:42 INFO - ^ 02:08:42 INFO - 1 warning generated. 02:08:42 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 02:08:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:42 INFO - Successfully installed psutil 02:08:42 INFO - Cleaning up... 02:08:42 INFO - Return code: 0 02:08:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:42 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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:42 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 02:08:42 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 02:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:42 INFO - 'HOME': '/Users/cltbld', 02:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:42 INFO - 'LOGNAME': 'cltbld', 02:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:42 INFO - 'MOZ_NO_REMOTE': '1', 02:08:42 INFO - 'NO_EM_RESTART': '1', 02:08:42 INFO - 'PAGER': '/bin/cat', 02:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:42 INFO - 'PWD': '/builds/slave/test', 02:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:42 INFO - 'SHELL': '/bin/bash', 02:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:42 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:42 INFO - 'USER': 'cltbld', 02:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:42 INFO - 'XPC_FLAGS': '0x0', 02:08:42 INFO - 'XPC_SERVICE_NAME': '0', 02:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:42 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 02:08:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:08:45 INFO - Installing collected packages: mozsystemmonitor 02:08:45 INFO - Running setup.py install for mozsystemmonitor 02:08:45 INFO - Successfully installed mozsystemmonitor 02:08:45 INFO - Cleaning up... 02:08:45 INFO - Return code: 0 02:08:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:45 INFO - 'HOME': '/Users/cltbld', 02:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:45 INFO - 'LOGNAME': 'cltbld', 02:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:45 INFO - 'MOZ_NO_REMOTE': '1', 02:08:45 INFO - 'NO_EM_RESTART': '1', 02:08:45 INFO - 'PAGER': '/bin/cat', 02:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:45 INFO - 'PWD': '/builds/slave/test', 02:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:45 INFO - 'SHELL': '/bin/bash', 02:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:45 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:45 INFO - 'USER': 'cltbld', 02:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:45 INFO - 'XPC_FLAGS': '0x0', 02:08:45 INFO - 'XPC_SERVICE_NAME': '0', 02:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:45 INFO - Downloading/unpacking jsonschema==2.5.1 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:08:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 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 02:08:48 INFO - Downloading functools32-3.2.3-2.tar.gz 02:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:08:48 INFO - warning: no files found matching '*.txt' 02:08:48 INFO - no previously-included directories found matching 'build' 02:08:48 INFO - no previously-included directories found matching 'dist' 02:08:48 INFO - no previously-included directories found matching '.git*' 02:08:48 INFO - Installing collected packages: jsonschema, functools32 02:08:48 INFO - Running setup.py install for functools32 02:08:49 INFO - warning: no files found matching '*.txt' 02:08:49 INFO - no previously-included directories found matching 'build' 02:08:49 INFO - no previously-included directories found matching 'dist' 02:08:49 INFO - no previously-included directories found matching '.git*' 02:08:49 INFO - Successfully installed jsonschema functools32 02:08:49 INFO - Cleaning up... 02:08:49 INFO - Return code: 0 02:08:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:49 INFO - 'HOME': '/Users/cltbld', 02:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:49 INFO - 'LOGNAME': 'cltbld', 02:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:49 INFO - 'MOZ_NO_REMOTE': '1', 02:08:49 INFO - 'NO_EM_RESTART': '1', 02:08:49 INFO - 'PAGER': '/bin/cat', 02:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:49 INFO - 'PWD': '/builds/slave/test', 02:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:49 INFO - 'SHELL': '/bin/bash', 02:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:49 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:49 INFO - 'USER': 'cltbld', 02:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:49 INFO - 'XPC_FLAGS': '0x0', 02:08:49 INFO - 'XPC_SERVICE_NAME': '0', 02:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:08:49 INFO - Cleaning up... 02:08:49 INFO - Return code: 0 02:08:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:08:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:49 INFO - 'HOME': '/Users/cltbld', 02:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:49 INFO - 'LOGNAME': 'cltbld', 02:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:49 INFO - 'MOZ_NO_REMOTE': '1', 02:08:49 INFO - 'NO_EM_RESTART': '1', 02:08:49 INFO - 'PAGER': '/bin/cat', 02:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:49 INFO - 'PWD': '/builds/slave/test', 02:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:49 INFO - 'SHELL': '/bin/bash', 02:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:49 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:49 INFO - 'USER': 'cltbld', 02:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:49 INFO - 'XPC_FLAGS': '0x0', 02:08:49 INFO - 'XPC_SERVICE_NAME': '0', 02:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:49 INFO - Downloading/unpacking blobuploader==1.2.4 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08:52 INFO - Downloading blobuploader-1.2.4.tar.gz 02:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:08:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08: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 02:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:08:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:53 INFO - Downloading docopt-0.6.1.tar.gz 02:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:08:53 INFO - Installing collected packages: blobuploader, requests, docopt 02:08:53 INFO - Running setup.py install for blobuploader 02:08:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:08:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:08:53 INFO - Running setup.py install for requests 02:08:54 INFO - Running setup.py install for docopt 02:08:54 INFO - Successfully installed blobuploader requests docopt 02:08:54 INFO - Cleaning up... 02:08:54 INFO - Return code: 0 02:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:54 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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:54 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 02:08:54 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 02:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:54 INFO - 'HOME': '/Users/cltbld', 02:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:54 INFO - 'LOGNAME': 'cltbld', 02:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:54 INFO - 'MOZ_NO_REMOTE': '1', 02:08:54 INFO - 'NO_EM_RESTART': '1', 02:08:54 INFO - 'PAGER': '/bin/cat', 02:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:54 INFO - 'PWD': '/builds/slave/test', 02:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:54 INFO - 'SHELL': '/bin/bash', 02:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:54 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:54 INFO - 'USER': 'cltbld', 02:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:54 INFO - 'XPC_FLAGS': '0x0', 02:08:54 INFO - 'XPC_SERVICE_NAME': '0', 02:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:54 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ilzJPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:54 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Lggbic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:54 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-vy08Ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-AsK0ay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-LP73Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-m1fbyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-66YU8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-mHN0WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-5CnS7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-0QFn_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-6ds92K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-zWqCj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:55 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-bTblVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:56 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-fi5DDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:56 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-1Kzh9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:56 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-x1DsWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:56 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-X5L2KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:08:56 INFO - Running setup.py install for manifestparser 02:08:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:08:56 INFO - Running setup.py install for mozcrash 02:08:56 INFO - Running setup.py install for mozdebug 02:08:56 INFO - Running setup.py install for mozdevice 02:08:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozfile 02:08:57 INFO - Running setup.py install for mozhttpd 02:08:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozinfo 02:08:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozInstall 02:08:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for mozleak 02:08:57 INFO - Running setup.py install for mozlog 02:08:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:08:57 INFO - Running setup.py install for moznetwork 02:08:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozprocess 02:08:58 INFO - Running setup.py install for mozprofile 02:08:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozrunner 02:08:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:08:58 INFO - Running setup.py install for mozscreenshot 02:08:58 INFO - Running setup.py install for moztest 02:08:58 INFO - Running setup.py install for mozversion 02:08:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:08:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:08:59 INFO - Cleaning up... 02:08:59 INFO - Return code: 0 02:08:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:59 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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:08:59 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 02:08:59 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 02:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:59 INFO - 'HOME': '/Users/cltbld', 02:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:59 INFO - 'LOGNAME': 'cltbld', 02:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:59 INFO - 'MOZ_NO_REMOTE': '1', 02:08:59 INFO - 'NO_EM_RESTART': '1', 02:08:59 INFO - 'PAGER': '/bin/cat', 02:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:59 INFO - 'PWD': '/builds/slave/test', 02:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:59 INFO - 'SHELL': '/bin/bash', 02:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:08:59 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:59 INFO - 'USER': 'cltbld', 02:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:59 INFO - 'XPC_FLAGS': '0x0', 02:08:59 INFO - 'XPC_SERVICE_NAME': '0', 02:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:59 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-7ebiLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:59 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-HHsZyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:59 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-eaxHAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:59 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9EVLUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:59 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-FMQ1D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:59 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-NEN7IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:59 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)) 02:08:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-MsThj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-EreHkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Vv5_4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-rECc9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-05MPy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-z_T6cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-4tb19d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-QcMIwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-PU_pK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:09:00 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)) 02:09:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:09:00 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-2ZsgT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:09:01 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)) 02:09:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:09:01 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-YYmgr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:09:01 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)) 02:09:01 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)) 02:09:01 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)) 02:09:01 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)) 02:09:01 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)) 02:09:01 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)) 02:09:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:09:01 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 02:09:01 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 02:09:01 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 02:09:01 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 02:09:01 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 02:09:01 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 02:09:03 INFO - Downloading blessings-1.6.tar.gz 02:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:09:03 INFO - Installing collected packages: blessings 02:09:03 INFO - Running setup.py install for blessings 02:09:03 INFO - Successfully installed blessings 02:09:03 INFO - Cleaning up... 02:09:04 INFO - Return code: 0 02:09:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:04 INFO - Reading from file tmpfile_stdout 02:09:04 INFO - Current package versions: 02:09:04 INFO - blessings == 1.6 02:09:04 INFO - blobuploader == 1.2.4 02:09:04 INFO - docopt == 0.6.1 02:09:04 INFO - functools32 == 3.2.3-2 02:09:04 INFO - jsonschema == 2.5.1 02:09:04 INFO - manifestparser == 1.1 02:09:04 INFO - mozInstall == 1.12 02:09:04 INFO - mozcrash == 0.17 02:09:04 INFO - mozdebug == 0.1 02:09:04 INFO - mozdevice == 0.48 02:09:04 INFO - mozfile == 1.2 02:09:04 INFO - mozhttpd == 0.7 02:09:04 INFO - mozinfo == 0.9 02:09:04 INFO - mozleak == 0.1 02:09:04 INFO - mozlog == 3.3 02:09:04 INFO - moznetwork == 0.27 02:09:04 INFO - mozprocess == 0.23 02:09:04 INFO - mozprofile == 0.28 02:09:04 INFO - mozrunner == 6.12 02:09:04 INFO - mozscreenshot == 0.1 02:09:04 INFO - mozsystemmonitor == 0.3 02:09:04 INFO - moztest == 0.7 02:09:04 INFO - mozversion == 1.4 02:09:04 INFO - psutil == 3.1.1 02:09:04 INFO - requests == 1.2.3 02:09:04 INFO - wsgiref == 0.1.2 02:09:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:04 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 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:09:04 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 02:09:04 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 02:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:04 INFO - 'HOME': '/Users/cltbld', 02:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:04 INFO - 'LOGNAME': 'cltbld', 02:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:04 INFO - 'MOZ_NO_REMOTE': '1', 02:09:04 INFO - 'NO_EM_RESTART': '1', 02:09:04 INFO - 'PAGER': '/bin/cat', 02:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:04 INFO - 'PWD': '/builds/slave/test', 02:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:04 INFO - 'SHELL': '/bin/bash', 02:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:09:04 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:04 INFO - 'USER': 'cltbld', 02:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:04 INFO - 'XPC_FLAGS': '0x0', 02:09:04 INFO - 'XPC_SERVICE_NAME': '0', 02:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 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)) 02:09:04 INFO - Cleaning up... 02:09:04 INFO - Return code: 0 02:09:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9761d76020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, '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.mScJaB2P0S/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.MHCpOKSAGA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 02:09:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:04 INFO - 'HOME': '/Users/cltbld', 02:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:04 INFO - 'LOGNAME': 'cltbld', 02:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:04 INFO - 'MOZ_NO_REMOTE': '1', 02:09:04 INFO - 'NO_EM_RESTART': '1', 02:09:04 INFO - 'PAGER': '/bin/cat', 02:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:04 INFO - 'PWD': '/builds/slave/test', 02:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:04 INFO - 'SHELL': '/bin/bash', 02:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:09:04 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:04 INFO - 'USER': 'cltbld', 02:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:04 INFO - 'XPC_FLAGS': '0x0', 02:09:04 INFO - 'XPC_SERVICE_NAME': '0', 02:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:09:04 INFO - Cleaning up... 02:09:04 INFO - Return code: 0 02:09:04 INFO - Running post-action listener: _resource_record_post_action 02:09:04 INFO - Running post-action listener: _start_resource_monitoring 02:09:04 INFO - Starting resource monitoring. 02:09:04 INFO - [mozharness: 2016-09-10 09:09:04.854673Z] Finished create-virtualenv step (success) 02:09:04 INFO - [mozharness: 2016-09-10 09:09:04.855647Z] Running install step. 02:09:04 INFO - Running pre-action listener: _resource_record_pre_action 02:09:04 INFO - Running main action method: install 02:09:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:09:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:09:05 INFO - Reading from file tmpfile_stdout 02:09:05 INFO - Detecting whether we're running mozinstall >=1.0... 02:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:09:05 INFO - Reading from file tmpfile_stdout 02:09:05 INFO - Output received: 02:09:05 INFO - Usage: mozinstall [options] installer 02:09:05 INFO - Options: 02:09:05 INFO - -h, --help show this help message and exit 02:09:05 INFO - -d DEST, --destination=DEST 02:09:05 INFO - Directory to install application into. [default: 02:09:05 INFO - "/builds/slave/test"] 02:09:05 INFO - --app=APP Application being installed. [default: firefox] 02:09:05 INFO - mkdir: /builds/slave/test/build/application 02:09:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:09:39 INFO - Reading from file tmpfile_stdout 02:09:39 INFO - Output received: 02:09:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:09:39 INFO - Running post-action listener: _resource_record_post_action 02:09:39 INFO - [mozharness: 2016-09-10 09:09:39.151221Z] Finished install step (success) 02:09:39 INFO - [mozharness: 2016-09-10 09:09:39.151354Z] Running run-tests step. 02:09:39 INFO - Running pre-action listener: _resource_record_pre_action 02:09:39 INFO - Running main action method: run_tests 02:09:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:09:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:09:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:09:39 INFO - 2016-09-10 02:09:39.164 screenresolution[2031:14971] starting screenresolution argv=screenresolution get 02:09:39 INFO - 2016-09-10 02:09:39.167 screenresolution[2031:14971] Display 0: 1600x1200x32@60 02:09:39 INFO - 2016-09-10 02:09:39.172 screenresolution[2032:14973] starting screenresolution argv=screenresolution list 02:09:39 INFO - Available Modes on Display 0 02:09:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:09:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:09:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:09:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:09:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:09:39 INFO - Intel Iris: 02:09:39 INFO - Chipset Model: Intel Iris 02:09:39 INFO - Type: GPU 02:09:39 INFO - Bus: Built-In 02:09:39 INFO - VRAM (Dynamic, Max): 1536 MB 02:09:39 INFO - Vendor: Intel (0x8086) 02:09:39 INFO - Device ID: 0x0a2e 02:09:39 INFO - Revision ID: 0x0009 02:09:39 INFO - Displays: 02:09:39 INFO - Display: 02:09:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:09:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:09:39 INFO - Display Serial Number: 1600x1200 60 02:09:39 INFO - Main Display: Yes 02:09:39 INFO - Mirror: Off 02:09:39 INFO - Online: Yes 02:09:39 INFO - Rotation: Supported 02:09:39 INFO - Return code: 0 02:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:09:39 INFO - Python 2.7.3 02:09:39 INFO - Return code: 0 02:09:39 INFO - grabbing minidump binary from tooltool 02:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:39 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 0x10366ad30>, '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 0x103490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103662b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:09:39 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 02:09:39 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 02:09:39 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 02:09:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:09:39 INFO - Return code: 0 02:09:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:09:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:09:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:09:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:09:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:09:39 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/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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 02:09:39 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/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0333 --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 02:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mScJaB2P0S/Render', 02:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:39 INFO - 'HOME': '/Users/cltbld', 02:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:39 INFO - 'LOGNAME': 'cltbld', 02:09:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:39 INFO - 'MOZ_NO_REMOTE': '1', 02:09:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:39 INFO - 'NO_EM_RESTART': '1', 02:09:39 INFO - 'PAGER': '/bin/cat', 02:09:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:39 INFO - 'PWD': '/builds/slave/test', 02:09:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:39 INFO - 'SHELL': '/bin/bash', 02:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MHCpOKSAGA/Listeners', 02:09:39 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 02:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:39 INFO - 'USER': 'cltbld', 02:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:39 INFO - 'XPC_FLAGS': '0x0', 02:09:39 INFO - 'XPC_SERVICE_NAME': '0', 02:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:39 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/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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 02:09:39 INFO - mozversion application_buildid: 20160910001520 02:09:39 INFO - mozversion application_changeset: a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c 02:09:39 INFO - mozversion application_display_name: Nightly 02:09:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:09:39 INFO - mozversion application_name: Firefox 02:09:39 INFO - mozversion application_remotingname: firefox 02:09:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:09:39 INFO - mozversion application_vendor: Mozilla 02:09:39 INFO - mozversion application_version: 51.0a1 02:09:39 INFO - mozversion platform_buildid: 20160910001520 02:09:39 INFO - mozversion platform_changeset: a07fb64ae0a7dc76aa0f8e7067ed169baf11ef7c 02:09:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:09:39 INFO - mozversion platform_version: 51.0a1 02:09:39 INFO - using testdate: 1473498579 02:09:39 INFO - actual date: 1473498579 02:09:39 INFO - starting webserver on 'localhost:49960' 02:09:39 INFO - SUITE-START | Running 2 tests 02:09:39 INFO - TEST-START | dromaeo_css 02:09:39 INFO - operating with platform_type : mac_ 02:09:39 INFO - Initialising browser for dromaeo_css test... 02:09:39 INFO - TEST-INFO | started process 2042 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmp7S6CAD/profile http://localhost:49960/getInfo.html) 02:09:42 INFO - PROCESS | 2042 | __metrics Screen width/height:1600/1200 02:09:42 INFO - PROCESS | 2042 | colorDepth:24 02:09:42 INFO - PROCESS | 2042 | Browser inner width/height: 1024/689 02:09:42 INFO - PROCESS | 2042 | __metrics 02:09:42 ERROR - PROCESS | 2042 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 02:09:43 INFO - TEST-INFO | 2042: exit 0 02:09:43 INFO - Browser initialized. 02:09:43 INFO - Running cycle 1/1 for dromaeo_css test... 02:09:43 INFO - TEST-INFO | started process 2045 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmp7S6CAD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:55 INFO - PROCESS | 2045 | RSS: Main: 244219904 02:09:55 INFO - PROCESS | 2045 | 02:10:45 INFO - PROCESS | 2045 | 1473498645102 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] 02:12:56 INFO - PROCESS | 2045 | Cycle 1(1): loaded http://localhost:49960/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49960/tests/dromaeo/cssquery-ext.html) 02:12:58 INFO - PROCESS | 2045 | RSS: Main: 261812224 02:12:58 INFO - PROCESS | 2045 | 02:16:00 INFO - PROCESS | 2045 | Cycle 1(1): loaded http://localhost:49960/tests/dromaeo/cssquery-ext.html (next: http://localhost:49960/tests/dromaeo/cssquery-jquery.html) 02:16:01 INFO - PROCESS | 2045 | RSS: Main: 266694656 02:16:01 INFO - PROCESS | 2045 | 02:19:03 INFO - PROCESS | 2045 | Cycle 1(1): loaded http://localhost:49960/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49960/tests/dromaeo/cssquery-mootools.html) 02:19:03 INFO - PROCESS | 2045 | RSS: Main: 272465920 02:19:03 INFO - PROCESS | 2045 | 02:22:05 INFO - PROCESS | 2045 | Cycle 1(1): loaded http://localhost:49960/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49960/tests/dromaeo/cssquery-prototype.html) 02:22:06 INFO - PROCESS | 2045 | RSS: Main: 269643776 02:22:06 INFO - PROCESS | 2045 | 02:25:09 INFO - PROCESS | 2045 | Cycle 1(1): loaded http://localhost:49960/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49960/tests/dromaeo/cssquery-yui.html) 02:25:09 INFO - PROCESS | 2045 | RSS: Main: 276123648 02:25:09 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | Cycle 1(1): loaded http://localhost:49960/tests/dromaeo/cssquery-yui.html (next: http://localhost:49960/tests/dromaeo/cssquery-dojo.html) 02:28:12 INFO - PROCESS | 2045 | RSS: Main: 279035904 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | __start_tp_report 02:28:12 INFO - PROCESS | 2045 | _x_x_mozilla_page_load 02:28:12 INFO - PROCESS | 2045 | _x_x_mozilla_page_load_details 02:28:12 INFO - PROCESS | 2045 | |i|pagename|runs| 02:28:12 INFO - PROCESS | 2045 | |0;dojo.html;2925.3125515503866;2983.2094494958837;3418.982905085474;3424.7773894696843;3455.8617655293774;4671.883202919928;4725.362076119723;5727.083666613343;5743;5836.533077353812;5701.600887937846;5784.363719990798;5820.708964551776;5874.3244526879425;5924.229850119489;5230.790768369261;5253.264542963988;5280;5301.125314323132;5370.543503802162;5297.205419187134;5322.2548843161985;5353.170258609902;5361.919571206435;5382.865428364283;5185.740712964337;5239.476052394768;5294.549963253128;5296.766352533325;5319.521243088121;4906.386701662293;5184.351956005499;5239.056969745444;5280.392754833205;5357.491038351351;4711.7879695413785;5217.34783152106;5264.184051472028;5265.815696450606;5293.814716484943;5674.773009079632;5724.79963201286;5734.97132514339;5819;5842.620229685057;6156.415140561684;6157.891579515685;6170.167027451282;6267.843303917393;6269.435750782452;6252.06219067139;6266.686665666698;6270.996640537492;6271.059341098826;6280.434760871498;5915.792947246826;5938.8218353449465;5953.672548009858;5978.560125404926;5997.070454079625;6119.685352982159;6141.762184092961;6225.968870155711;6234.314225435199;6288.717007734572;6165.969170154211;6223.471004964629;6238.875222495525;6241.594296370721;6255.186825712628;6224.221972253468;6261.154744109578;6272;6272;6279.654618995908;6119.258838874149;6154.938450615526;6178.66017369049;6185.443310102112;6192.195014648067;6216.720247588779;6216.720247588869;6230.7819226327765;6239.532035097386;6275.874482510324;4952.4304704959395;4964.8758781030765;4974.875628109254;4983.601311895112;4994.550490455877;4411.911761764747;4433.977830110829;4441.289393697058;4514.0971805639;4514.88712782183;5084.084864724311;5376.892462150735;5377.354717433933;5403.081476149064;5436.238926550286;6167.876642467126;6222.471089957314;6223.719932603044;6226.6575338355915;6271;4604.871806407452;5286.995470860525;5351.117065684196;5354.330708661418;5388.353397592314;4404.6256068233915;5278.9472105278455;5308.893822123536;5316.308879845596;5361.865953351197;6350.333215012357;6365.96817015912;6377.107204991212;6388.361163883575;6398.936010639834;6101.065651476929;6183.288921774054;6218.844528886814;6223.502119830493;6252.562320637512;6232.283287421823;6295.150154729053;6308.968455157695;6332.873342533031;6384.265809431789;6234.37656234391;6237.376262373727;6243.937560624518;6247.468965137832;6275.811725648238;5942.58401911862;6177.980633195472;6236.594621349779;6239.656818875005;6246.1255424239735;5995.760169593167;6023.66869822164;6090.756369745161;6108.584917381041;6153.969230153821;6133.579501509479;6170.074488826712;6191.349908259568;6203.286622038523;6222.097795819672;5957.642541447527;6042.123892035543;6055.515558755201;6121;6123.4488895999575;6108.797423689751;6163.722632481459;6195.380461953769;6197.163382943425;6245.250569931637;6074.878502430015;6097.420745029215;6108.784113181727;6110.533471966785;6112.5679851514815 02:28:12 INFO - PROCESS | 2045 | |1;ext.html;525.1571228178773;534.9438308977557;539.9406065332813;544.6459801129268;558.391353424767;40050;41812.790936045276;41818.37272440912;41879.37180942281;41895.58104418955;42367.78816105923;42490;42780.786096069554;42808;42938;9946.25403094769;10096;10132.14978308242;10135;10157.796844063114;9851.802963940718;9915.405075695446;9940.453275069869;9950.751231219241;9966.501674916262;9942.154916832078;9951;9981.75045623858;9983.40099594023;9999.10008099271;9892;9905.603775848993;9924.45415502147;9954.601815927317;10016.399016058987;9910.256730745223;9987.600495980152;10015.69952901414;10021.79956400868;10024.348417352921;41172.38241426381;41234.587654123374;41460.37809432861;41539.961500962716;41581.376279355834;2942.8528573571234;3038.8176709397508;3551.0944709099012;3591;3721.8697345592773;31054.844725776227;31082.844585776926;31352.21619459509;31465;32231.355372892413;2752.775015118273;3240.6111266648154;3246.0586429935524;3259.445894197992;3369.393509168349;500880;503462;504026;505265;507970;57300.71349643308;57717.422825772046;57779.13331300034;57862.7106864463;57882.710586446796;34325.828370858486;34593.308133837185;34781.826090869385;36264.27471450609;60861;16489.422870199487;16640.583985400463;16641.251143698562;16650.583735406468;16674.916625416794;12602.243865368107;12726.681832954066;12830.230186188859;12831.294278814756;12852.807207891889;18696.439106826816;18806.90596547036;18816.529586760167;18833;18882.055897205086;7615.504992175491;7774.416918731118;7939.245771651684;8005.639746211435;8027.5584842834205;834.1783343406739;896.251629889037;926.2404828040942;957.0525180071679;1053.9262251642313;28755.856220719183;30017.94937177232;30040.54939175914;30148;30606.234844129074;1023.181454836143;1053.0575135254057;1116.263266244275;1124.6738445850613;1135.5628083187867;12846.522649895194;13918;15428.922855385652;15518;15609.843901560838;713550;716561;720902;723586;724250;56158.71920640452;57354.426455735746;57638.71180644154;57654;57677;1348.0900392235399;1382.315753701904;1396.4833011785906;1398.6643205630576;1400.6008287637974;28493;29400.852995734884;29450.852745736134;29678.109656710334;30294.394112118076;46261.07477850492;49076.773080673265;49134;49203.26195107148;49206.75396622994;617619;623903;623958;624511;628447;22027.00878460441;25378;25775.6133657999;26206.737932621192;26297.868510657325;44408.77795611001;44752.77623611861;45322.77338613286;45501.54498455105;45611;36390.818045909604;38176.236475270896;38214;39037;39685.80942571728;37748.2450350986;38108.61891381162;38996.025099372746;39447.802760986015;39643.80178099091;36630.8168459156;37058.444123337635;37953.48186072526;38355.849324520306;39038.6096139035;27441.86279068672;29081;30075.548866766578;32973.17567060843;33196.83401583073;25249.87375063113;25365;25583;25964.74035259699;26103.738962610132 02:28:12 INFO - PROCESS | 2045 | |2;jquery.html;369.88903329001295;371.7657875538409;376.43346763121497;378.10766590845583;401.63250625677506;18983.145758440867;20245.797542024575;20514.794852051476;20531.589368212626;20844;17819.554511137194;17903.820961790378;18193.909030454863;20443.080061397235;20943.58112837746;5970.432808883149;6017.819465416044;6155.907661385083;6165.537584681145;6258.436740693337;4200.138971510848;5359.330083739533;6069;6171;6189.690515474208;4367.934480982788;5723.885522289552;6457.096006559085;6808.2510923798345;6861.073755043055;5768.961586914354;6593.93406065938;6834.9658251708925;6859.897101543481;6882.070920425728;5958.97020514899;5961.552883533753;5967.850803729942;5977.923973684735;5979.342272350038;26011;26204.475910481684;26206;26390.812413441443;26670.5999410009;2212.0598745533243;2228.6099932511747;2328.231683544443;2661.614065960425;2860.156253905103;15518.61203469911;15745.921270393575;15949.202539873077;16042.43851465193;16632.916835415745;1672.247488630109;1700.1499250374814;1886.76415448069;1897.5635603811202;1912.8469722422174;119381.40309298398;124970.37514812459;139083;140346.38614455308;142599;11259.662210133709;11332.490037948148;11513.021393181505;11532.019778318769;11726.413679316;14553.816927460966;14571;14574.781378279122;14608.707825843423;14614.561563153122;7158.713651453883;8312.750617481484;8484.024337201177;8540.487570745776;8640.654373824977;8453.281471074904;8495.66017359311;8521.233089022018;8645.351598630003;8681.435706679173;4432.246518091933;4479.955200447954;4533.229351010336;4577.908441831145;4599.632029437704;1580.162513867652;1880.9153588088568;1917.893013318433;1924.70167124096;1927.1038966880467;908.8227795579799;914.4063829151366;940.6284517615503;944.2823454174877;957.674390707149;21768;22123.889380553315;22477.29124694407;22505.774942250697;22741.090356385892;925.5464822237055;934.2852717670987;955.9091886270793;964.8070385922844;1028.5063169678447;9816.852747208797;11279.436028198557;11555.711107222218;12204.633860984184;12239.510419583296;527161;527208;632085;638673;643097;137589.3120534391;141176;146667;149692;153648;425.5446672060865;441.6180004296261;446.1923917708957;446.49546013005096;450.36498537062585;9115.27077833785;9749.317547771589;10202.438865862448;11120.721981950515;11791.469383877575;53878.73060634828;55989;56624.716876415354;58846.82306353935;59290.40709592849;64343;72043;73337;82798.17201827905;86883.26233475421;8124.39067069984;8169.7745338197365;8327.333813294801;8526.488410695378;8540.487570745776;12189.75620487603;12437.253764774143;13155.079144459796;13336.733010364;13520;9630;11052.723681908017;11345.716357091138;11426.743058263419;11480.655580332603;11056.613018544313;11217.158713096387;11262.04272636835;11279.266847654877;11662.53349865996;11413.60052398162;11416;11472.31166130035;11775.882241177822;11797.35114568707;7866.372893467389;7954.528412243753;8019.679212831421;8077.676892924453;8186.222308880647;8121.675132994614;10627.36235825853;11164.832527512257;11348.659540213806;11712.355820429959 02:28:12 INFO - PROCESS | 2045 | |3;mootools.html;1467.1490535489413;1516.8710659593935;1736.800267969184;1878.8872667639935;1892.4228110426322;8137.18628137186;8440.704575339865;8613;9179.311551633638;9210.217131543819;8592.441491303061;8935.91064089359;8939.106089391058;9036.95481522593;9040.276777857773;2382.1900553811656;2421.842580232288;2422.648715936188;2430.647556104364;2430.7083150021936;2378.726446458662;2408.89159987801;2423.0307876849224;2433.3916520869784;2434.6834911461483;2356.9982757328153;2398.8440751351104;2403.6875206223167;2419.2742177346813;2423.987880060597;2113.92601258956;2376.1208352909534;2394.1740099665676;2401.5677178107935;2403.447207142367;2370.111208296889;2388.725296590897;2398.1126983016397;2399.7360290368047;2400.543896659629;7627.580483073429;8977;9080.954595227047;9111.954440227822;9130.360874738772;5284.365876094855;5437.537809286216;5441.945580544183;5449.56403487724;5458.2904222451225;3633.709303255733;3637.1452708613456;3679.356112680297;3699.5005674233903;3718.9070273243115;1081.1783044885942;1083.1713738989679;1089.6458650938398;1099.6646022962993;1102.3441052573705;11891.881081189164;11999.460024298927;12076.033917286595;12077.939610301892;12087.758244835142;4508.571685689854;4692.343071969927;4780.904381912343;4882.951170488321;4885.902281954341;10160.745981350525;10181.440020798927;10241.07830295277;10252.179825614083;10255.487225638688;5375.704336261465;5382.91925621116;5387.838364849061;5389.487998640123;5410.296661434067;4443.488998765119;4604.263317869125;4806.255030470283;4927.457979622239;4941.703497790144;3830.7701537907815;3948.980255098706;3963.4847469829315;4081.959180408158;4126.855560055384;1127.8421021057059;1134.5915470430639;1161.233585833363;1172.0916289875245;1340.8390993080893;777.5995362388375;796.665400531778;839.8068444257855;925.4956048953337;957.2868213181224;5798.33319168301;5951.732172052181;6015.428534289235;6132.264128304632;6309.305976342599;448.717308095904;454.22101096619457;456.88121088517227;465.4344970860353;467.0215897694325;3323.93352132956;3449.275652113034;4030.9395359069636;4100.118474527991;4147.357159640261;10087.193024558017;10333.689989300334;10498.10766084876;10743.570257189782;11032.007119359296;11065.948734870175;11107.888921110847;11109.111271098292;11116.999470047573;11145.554177832959;7416.887466880011;7915.762527124196;8131.796705082302;8302.252797248277;8359.456635318686;5895.351511333835;5938.554608404474;5977.760889564369;6031.065184896348;6032.607880487754;10267.589296428358;10362.585496580354;10364.378137311785;10388.584456621651;10437;9833.311668183158;10321.174306055647;10350.585976560853;10468.947655261674;10495.737606559896;7036.859262814817;7230;7281.162666293444;7421.035265415461;7726.536407815549;8349.37379696513;8634.395592308729;8651.394402391772;8651.567421629019;8665.09016553278;7591.962040189764;7731.265529774662;7761.72833950809;7831.451798374287;7859.724909628136;7444.143923448656;7574.962125189339;7598.088229412285;7612.429067820047;7633.580153091633;6989;7095.254998225112;7095.538789978635;7102.431805455654;7111.82220444493;4925.556699896883;4988.850334489971;5020.698758074527;5064.721440320818;5184.66299690534;5046.318746969112;5059.392872855281;5087.491250874883;5168.870778230575;5335 02:28:12 INFO - PROCESS | 2045 | |4;prototype.html;259.8181273108824;282.7935607006885;292.9853507324634;308.29400672460054;310.38853458686395;5354.196870469432;5496.4503549645115;5620.803271885485;5652.293463317091;5673.177389278558;4484.708493947891;4486.6410687145035;4774.522547745224;5483.7532311046;5537.446255374461;3592.0121966459174;3628.095249993761;3934.960650393488;4085.9387109193385;4218.493780746315;4145.922060264338;4163.791810409482;4181.811818468176;4182.91634167318;4188.246115699173;3713.1459764254237;4040.7373520721058;4151.169766046803;4161.542230354658;4171.541130475645;4003.768949577973;4071.4503542021744;4072.7556346619003;4124.752514849119;4153.8961525961795;2993.760499160062;3570.3037907608027;3572.7677700949357;3575.5340310472843;3587.4260118381194;5272.710000949946;5310.070737620939;5311.468853114697;5318.526884998456;5354.705491197982;3637.854485820564;4682.08034705769;4696.718196908197;4772.618190544783;4779.163646361872;5449.457852395934;5618.353889302701;5712.657525481509;5734.713264336809;5755.798547050833;3953.723239373245;3959.8020098994934;3961.4057891316247;3966.1869316790276;4003.179348233605;4779.641526885497;5622.690752008638;5955.612885162451;5996.370381109964;6068.605540639845;3905.2384784966657;3906.570277269498;3910.4720862683744;3916.7845768483007;3932.528096628423;3928.273269445162;3987.8803635890968;4006.399040144002;4028.8589899353383;4028.8992775180855;4727.550882666142;4730.858074257778;4739.123262196505;4751.287306903992;4774.522547745267;5553.666779993213;5853.2976042875125;5890.615705309249;5934.851628709317;5939.2575928009;1305.2103477396229;1311.6883116883116;1380.7297286496448;1395.8115654386527;1405.641561401836;772.1506343022743;787.8188016756178;827.0530242871998;834.7913021744564;891.5319457284977;4137.586241375839;4484.506704262529;4648.163330600524;4979.576735977483;5124.5131712487255;4492.483364413134;5456.181572764038;5719.685417302005;5843.28287346386;5942.673152976541;3787.4739686074877;4674.672772905932;5080.298100136496;5134.895997360585;5178.689278643294;3063.234191452137;3103.1466346754823;3104.580881580958;3116.6415862175686;3126.8436578171454;5234.110201265794;5933.673647949404;5939.70301484924;6154.7230374633245;6277.591956522902;5109.262850630804;5564.219449776114;6044.879102417927;6103.755849765959;6181.721822517998;5166.9224961625605;6103.572749907553;6125.418085281891;6147.477464415396;6167.198264225712;5623.325200975745;5909.674967876808;5929.555283353679;5943.494802941798;6016.112266112378;5308.920366194433;5368.7852485901685;5877.911831322619;5918.260217472816;5974.970125149346;5368.4631536846;5437.619366644297;6084;6160.815175544741;6168.629882207082;3416.164583395825;3441.913952151216;3456.084137703561;3664.2121943782213;3674;5047.899042019066;5657;5973.223480947449;5973.522118230444;5985.940140598538;5344.893102138013;5858.560607954335;5876.647401155944;5885.499732522783;5894.557908156991;4807.591354734747;5531.861703457445;5664.461876121762;5790.9420905792085;5797.710114494343;4823.903521929612;5729.169270455844;5758.424157584208;5772.913406298825;5804.73878675469;4695.812167513398;5517.972410137923;5646.322441307069;5717.056685646821;5854.941450585439;5696.974544582015;5718.599698021265;5738.770449181986;5761.567882408753;5764.855878602901 02:28:12 INFO - PROCESS | 2045 | |5;yui.html;614.923134608174;696.6899729620314;709.8970649255862;794.841031793641;804.0793291681027;15243.390264389425;15916.442924497645;15921;15927.362905483767;15942.963707359027;13959.371828267727;14084.36620352084;14252.572422827332;14355.354009069591;14404.927975360108;686.2965460403077;700.7687463137178;856.1395797223781;867.7440155154245;878.4641368765081;876.1545108969843;885.5749240364629;885.8405487012335;889.799795046116;890.2699786175339;913.127962795532;932.8600709893536;938.7700013496669;945.744648944788;946.1201082992814;922.3174850610534;940.2947789158166;942.3356533643764;943.0286804591248;946.186279799372;697.3723648716145;702.301210295759;703.6481759120439;705.7988473285089;705.9611721355324;14403.207823569699;14685.55943321702;14697.706045879022;14703;14704.485343012942;14580.562583122522;14623.561293161221;14652.633684157981;14678.92660536701;14829.629259268497;11752.588659396966;11876.465559049864;11960.46177921987;11987;12028.613444330882;3648.908777280563;3810.3903375460072;3833.961660383416;3942.8817135485983;3945.842166313344;78104.43791124203;94511.52744236303;98679;98969.50515247449;100615;10450.268481206394;10648.041676249175;10754.139668826476;10790.082842958436;10792.730181745363;20878.686819697716;21089.367318980454;21106.894465527883;21130.577388452144;21151.471213219484;7784.4940078895825;7961.402894782915;7966.123726390092;8235.176482351837;8300.252977232078;4439.889002774957;4524.23088075028;4547.47704014036;4554.3851580036935;4561.133384656904;3662.1760103976394;3775.6601905828056;3786.67813235878;3803.2583646188727;3815.351390263662;679.2290749998787;703.5286358140058;703.9964800175966;708.6704682322745;715.6028404235595;583.291301069203;650.8926027205458;663.1743479368217;663.6018388966581;682.7030241844814;8368.497890126611;8907.73276801684;8908.465492070496;9373.484458354857;9798.608055677692;618.7184830901929;640.4012248547623;676.7123972311748;680.6052489556046;681.2778454837887;3706.6478684524927;4404.867853964386;4733.786979585927;4832.9275060874115;4878;125694;140277.8958315626;142391;156830;178031;96477;107937.92062079436;109407.45296273468;118239.8176018246;121184;103007;103306;105238;106524;107794;9074.954625226832;9835.114839664464;9946.45294508809;9977.750556236153;10101.725694842708;39998;46692;51971;52730;54094.93018089614;86852.26295474182;92770;93146;93207.53396232975;97933;13908.304584770518;14030.438782448997;14068.577942661737;14122.929385353007;14782.852171478147;13846.723065538834;14331.85668143347;15031.022983506036;15396.923015385299;15753.921230393775;13897.791533126809;13917.860821391656;14390.85609143895;14614.70770584561;14625.707485850437;13895.722085558435;14150.009499334948;14239.857601423853;14379;14466.782998255245;13783.448662053406;13912.373943172784;13970;14118.50585229512;14405.279736012948;9971.601135954481;11398;11634.476448560004;12049.156559041134;12248.632541023784;9640.42157470554;9986.80817892911;10744.579645466614;11726.296422214695;11885.28688278706 02:28:12 INFO - PROCESS | 2045 | __end_tp_report 02:28:12 INFO - PROCESS | 2045 | __start_cc_report 02:28:12 INFO - PROCESS | 2045 | _x_x_mozilla_cycle_collect,373 02:28:12 INFO - PROCESS | 2045 | __end_cc_report 02:28:12 INFO - PROCESS | 2045 | __startTimestamp1473499692635__endTimestamp 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | ------- Summary: start ------- 02:28:12 INFO - PROCESS | 2045 | Number of tests: 6 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | [#0] dojo.html Cycles:165 Average:5731.88 Median:6090.76 stddev:685.68 (11.3%) stddev-sans-first:651.47 02:28:12 INFO - PROCESS | 2045 | Values: 2925.3 2983.2 3419.0 3424.8 3455.9 4671.9 4725.4 5727.1 5743.0 5836.5 5701.6 5784.4 5820.7 5874.3 5924.2 5230.8 5253.3 5280.0 5301.1 5370.5 5297.2 5322.3 5353.2 5361.9 5382.9 5185.7 5239.5 5294.5 5296.8 5319.5 4906.4 5184.4 5239.1 5280.4 5357.5 4711.8 5217.3 5264.2 5265.8 5293.8 5674.8 5724.8 5735.0 5819.0 5842.6 6156.4 6157.9 6170.2 6267.8 6269.4 6252.1 6266.7 6271.0 6271.1 6280.4 5915.8 5938.8 5953.7 5978.6 5997.1 6119.7 6141.8 6226.0 6234.3 6288.7 6166.0 6223.5 6238.9 6241.6 6255.2 6224.2 6261.2 6272.0 6272.0 6279.7 6119.3 6154.9 6178.7 6185.4 6192.2 6216.7 6216.7 6230.8 6239.5 6275.9 4952.4 4964.9 4974.9 4983.6 4994.6 4411.9 4434.0 4441.3 4514.1 4514.9 5084.1 5376.9 5377.4 5403.1 5436.2 6167.9 6222.5 6223.7 6226.7 6271.0 4604.9 5287.0 5351.1 5354.3 5388.4 4404.6 5278.9 5308.9 5316.3 5361.9 6350.3 6366.0 6377.1 6388.4 6398.9 6101.1 6183.3 6218.8 6223.5 6252.6 6232.3 6295.2 6309.0 6332.9 6384.3 6234.4 6237.4 6243.9 6247.5 6275.8 5942.6 6178.0 6236.6 6239.7 6246.1 5995.8 6023.7 6090.8 6108.6 6154.0 6133.6 6170.1 6191.3 6203.3 6222.1 5957.6 6042.1 6055.5 6121.0 6123.4 6108.8 6163.7 6195.4 6197.2 6245.3 6074.9 6097.4 6108.8 6110.5 6112.6 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | [#1] ext.html Cycles:180 Average:73344.73 Median:38284.92 stddev:166858.84 (435.8%) stddev-sans-first:167237.34 02:28:12 INFO - PROCESS | 2045 | Values: 525.2 534.9 539.9 544.6 558.4 40050.0 41812.8 41818.4 41879.4 41895.6 42367.8 42490.0 42780.8 42808.0 42938.0 9946.3 10096.0 10132.1 10135.0 10157.8 9851.8 9915.4 9940.5 9950.8 9966.5 9942.2 9951.0 9981.8 9983.4 9999.1 9892.0 9905.6 9924.5 9954.6 10016.4 9910.3 9987.6 10015.7 10021.8 10024.3 41172.4 41234.6 41460.4 41540.0 41581.4 2942.9 3038.8 3551.1 3591.0 3721.9 31054.8 31082.8 31352.2 31465.0 32231.4 2752.8 3240.6 3246.1 3259.4 3369.4 500880.0 503462.0 504026.0 505265.0 507970.0 57300.7 57717.4 57779.1 57862.7 57882.7 34325.8 34593.3 34781.8 36264.3 60861.0 16489.4 16640.6 16641.3 16650.6 16674.9 12602.2 12726.7 12830.2 12831.3 12852.8 18696.4 18806.9 18816.5 18833.0 18882.1 7615.5 7774.4 7939.2 8005.6 8027.6 834.2 896.3 926.2 957.1 1053.9 28755.9 30017.9 30040.5 30148.0 30606.2 1023.2 1053.1 1116.3 1124.7 1135.6 12846.5 13918.0 15428.9 15518.0 15609.8 713550.0 716561.0 720902.0 723586.0 724250.0 56158.7 57354.4 57638.7 57654.0 57677.0 1348.1 1382.3 1396.5 1398.7 1400.6 28493.0 29400.9 29450.9 29678.1 30294.4 46261.1 49076.8 49134.0 49203.3 49206.8 617619.0 623903.0 623958.0 624511.0 628447.0 22027.0 25378.0 25775.6 26206.7 26297.9 44408.8 44752.8 45322.8 45501.5 45611.0 36390.8 38176.2 38214.0 39037.0 39685.8 37748.2 38108.6 38996.0 39447.8 39643.8 36630.8 37058.4 37953.5 38355.8 39038.6 27441.9 29081.0 30075.5 32973.2 33196.8 25249.9 25365.0 25583.0 25964.7 26103.7 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | [#2] jquery.html Cycles:180 Average:35873.17 Median:1615.02 stddev:100446.22 (6219.5%) stddev-sans-first:100692.63 02:28:12 INFO - PROCESS | 2045 | Values: 369.9 371.8 376.4 378.1 401.6 18983.1 20245.8 20514.8 20531.6 20844.0 17819.6 17903.8 18193.9 20443.1 20943.6 5970.4 6017.8 6155.9 6165.5 6258.4 4200.1 5359.3 6069.0 6171.0 6189.7 4367.9 5723.9 6457.1 6808.3 6861.1 5769.0 6593.9 6835.0 6859.9 6882.1 5959.0 5961.6 5967.9 5977.9 5979.3 26011.0 26204.5 26206.0 26390.8 26670.6 2212.1 2228.6 2328.2 2661.6 2860.2 15518.6 15745.9 15949.2 16042.4 16632.9 1672.2 1700.1 1886.8 1897.6 1912.8 119381.4 124970.4 139083.0 140346.4 142599.0 11259.7 11332.5 11513.0 11532.0 11726.4 14553.8 14571.0 14574.8 14608.7 14614.6 7158.7 8312.8 8484.0 8540.5 8640.7 8453.3 8495.7 8521.2 8645.4 8681.4 4432.2 4480.0 4533.2 4577.9 4599.6 1580.2 1880.9 1917.9 1924.7 1927.1 908.8 914.4 940.6 944.3 957.7 21768.0 22123.9 22477.3 22505.8 22741.1 925.5 934.3 955.9 964.8 1028.5 9816.9 11279.4 11555.7 12204.6 12239.5 527161.0 527208.0 632085.0 638673.0 643097.0 137589.3 141176.0 146667.0 149692.0 153648.0 425.5 441.6 446.2 446.5 450.4 9115.3 9749.3 10202.4 11120.7 11791.5 53878.7 55989.0 56624.7 58846.8 59290.4 64343.0 72043.0 73337.0 82798.2 86883.3 8124.4 8169.8 8327.3 8526.5 8540.5 12189.8 12437.3 13155.1 13336.7 13520.0 9630.0 11052.7 11345.7 11426.7 11480.7 11056.6 11217.2 11262.0 11279.3 11662.5 11413.6 11416.0 11472.3 11775.9 11797.4 7866.4 7954.5 8019.7 8077.7 8186.2 8121.7 10627.4 11164.8 11348.7 11712.4 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | [#3] mootools.html Cycles:180 Average:5792.95 Median:466.23 stddev:3294.80 (706.7%) stddev-sans-first:3288.00 02:28:12 INFO - PROCESS | 2045 | Values: 1467.1 1516.9 1736.8 1878.9 1892.4 8137.2 8440.7 8613.0 9179.3 9210.2 8592.4 8935.9 8939.1 9037.0 9040.3 2382.2 2421.8 2422.6 2430.6 2430.7 2378.7 2408.9 2423.0 2433.4 2434.7 2357.0 2398.8 2403.7 2419.3 2424.0 2113.9 2376.1 2394.2 2401.6 2403.4 2370.1 2388.7 2398.1 2399.7 2400.5 7627.6 8977.0 9081.0 9112.0 9130.4 5284.4 5437.5 5441.9 5449.6 5458.3 3633.7 3637.1 3679.4 3699.5 3718.9 1081.2 1083.2 1089.6 1099.7 1102.3 11891.9 11999.5 12076.0 12077.9 12087.8 4508.6 4692.3 4780.9 4883.0 4885.9 10160.7 10181.4 10241.1 10252.2 10255.5 5375.7 5382.9 5387.8 5389.5 5410.3 4443.5 4604.3 4806.3 4927.5 4941.7 3830.8 3949.0 3963.5 4082.0 4126.9 1127.8 1134.6 1161.2 1172.1 1340.8 777.6 796.7 839.8 925.5 957.3 5798.3 5951.7 6015.4 6132.3 6309.3 448.7 454.2 456.9 465.4 467.0 3323.9 3449.3 4030.9 4100.1 4147.4 10087.2 10333.7 10498.1 10743.6 11032.0 11065.9 11107.9 11109.1 11117.0 11145.6 7416.9 7915.8 8131.8 8302.3 8359.5 5895.4 5938.6 5977.8 6031.1 6032.6 10267.6 10362.6 10364.4 10388.6 10437.0 9833.3 10321.2 10350.6 10468.9 10495.7 7036.9 7230.0 7281.2 7421.0 7726.5 8349.4 8634.4 8651.4 8651.6 8665.1 7592.0 7731.3 7761.7 7831.5 7859.7 7444.1 7575.0 7598.1 7612.4 7633.6 6989.0 7095.3 7095.5 7102.4 7111.8 4925.6 4988.9 5020.7 5064.7 5184.7 5046.3 5059.4 5087.5 5168.9 5335.0 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | [#4] prototype.html Cycles:180 Average:4631.26 Median:5206.40 stddev:1451.16 (27.9%) stddev-sans-first:1417.65 02:28:12 INFO - PROCESS | 2045 | Values: 259.8 282.8 293.0 308.3 310.4 5354.2 5496.5 5620.8 5652.3 5673.2 4484.7 4486.6 4774.5 5483.8 5537.4 3592.0 3628.1 3935.0 4085.9 4218.5 4145.9 4163.8 4181.8 4182.9 4188.2 3713.1 4040.7 4151.2 4161.5 4171.5 4003.8 4071.5 4072.8 4124.8 4153.9 2993.8 3570.3 3572.8 3575.5 3587.4 5272.7 5310.1 5311.5 5318.5 5354.7 3637.9 4682.1 4696.7 4772.6 4779.2 5449.5 5618.4 5712.7 5734.7 5755.8 3953.7 3959.8 3961.4 3966.2 4003.2 4779.6 5622.7 5955.6 5996.4 6068.6 3905.2 3906.6 3910.5 3916.8 3932.5 3928.3 3987.9 4006.4 4028.9 4028.9 4727.6 4730.9 4739.1 4751.3 4774.5 5553.7 5853.3 5890.6 5934.9 5939.3 1305.2 1311.7 1380.7 1395.8 1405.6 772.2 787.8 827.1 834.8 891.5 4137.6 4484.5 4648.2 4979.6 5124.5 4492.5 5456.2 5719.7 5843.3 5942.7 3787.5 4674.7 5080.3 5134.9 5178.7 3063.2 3103.1 3104.6 3116.6 3126.8 5234.1 5933.7 5939.7 6154.7 6277.6 5109.3 5564.2 6044.9 6103.8 6181.7 5166.9 6103.6 6125.4 6147.5 6167.2 5623.3 5909.7 5929.6 5943.5 6016.1 5308.9 5368.8 5877.9 5918.3 5975.0 5368.5 5437.6 6084.0 6160.8 6168.6 3416.2 3441.9 3456.1 3664.2 3674.0 5047.9 5657.0 5973.2 5973.5 5985.9 5344.9 5858.6 5876.6 5885.5 5894.6 4807.6 5531.9 5664.5 5790.9 5797.7 4823.9 5729.2 5758.4 5772.9 5804.7 4695.8 5518.0 5646.3 5717.1 5854.9 5697.0 5718.6 5738.8 5761.6 5764.9 02:28:12 INFO - PROCESS | 2045 | 02:28:12 INFO - PROCESS | 2045 | [#5] yui.html Cycles:180 Average:23553.49 Median:3824.66 stddev:36993.56 (967.2%) stddev-sans-first:37057.24 02:28:12 INFO - PROCESS | 2045 | Values: 614.9 696.7 709.9 794.8 804.1 15243.4 15916.4 15921.0 15927.4 15943.0 13959.4 14084.4 14252.6 14355.4 14404.9 686.3 700.8 856.1 867.7 878.5 876.2 885.6 885.8 889.8 890.3 913.1 932.9 938.8 945.7 946.1 922.3 940.3 942.3 943.0 946.2 697.4 702.3 703.6 705.8 706.0 14403.2 14685.6 14697.7 14703.0 14704.5 14580.6 14623.6 14652.6 14678.9 14829.6 11752.6 11876.5 11960.5 11987.0 12028.6 3648.9 3810.4 3834.0 3942.9 3945.8 78104.4 94511.5 98679.0 98969.5 100615.0 10450.3 10648.0 10754.1 10790.1 10792.7 20878.7 21089.4 21106.9 21130.6 21151.5 7784.5 7961.4 7966.1 8235.2 8300.3 4439.9 4524.2 4547.5 4554.4 4561.1 3662.2 3775.7 3786.7 3803.3 3815.4 679.2 703.5 704.0 708.7 715.6 583.3 650.9 663.2 663.6 682.7 8368.5 8907.7 8908.5 9373.5 9798.6 618.7 640.4 676.7 680.6 681.3 3706.6 4404.9 4733.8 4832.9 4878.0 125694.0 140277.9 142391.0 156830.0 178031.0 96477.0 107937.9 109407.5 118239.8 121184.0 103007.0 103306.0 105238.0 106524.0 107794.0 9075.0 9835.1 9946.5 9977.8 10101.7 39998.0 46692.0 51971.0 52730.0 54094.9 86852.3 92770.0 93146.0 93207.5 97933.0 13908.3 14030.4 14068.6 14122.9 14782.9 13846.7 14331.9 15031.0 15396.9 15753.9 13897.8 13917.9 14390.9 14614.7 14625.7 13895.7 14150.0 14239.9 14379.0 14466.8 13783.4 13912.4 13970.0 14118.5 14405.3 9971.6 11398.0 11634.5 12049.2 12248.6 9640.4 9986.8 10744.6 11726.3 11885.3 02:28:12 INFO - PROCESS | 2045 | -------- Summary: end -------- 02:28:12 INFO - PROCESS | 2045 | 02:28:13 INFO - TEST-INFO | 2045: exit 0 02:28:13 INFO - TEST-OK | dromaeo_css | took 1113520ms 02:28:13 INFO - TEST-START | kraken 02:28:13 INFO - operating with platform_type : mac_ 02:28:13 INFO - Initialising browser for kraken test... 02:28:13 INFO - TEST-INFO | started process 2055 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpqsKr7B/profile http://localhost:49960/getInfo.html) 02:28:16 INFO - PROCESS | 2055 | __metrics Screen width/height:1600/1200 02:28:16 INFO - PROCESS | 2055 | colorDepth:24 02:28:16 INFO - PROCESS | 2055 | Browser inner width/height: 1024/689 02:28:16 INFO - PROCESS | 2055 | __metrics 02:28:16 ERROR - PROCESS | 2055 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 02:28:16 INFO - TEST-INFO | 2055: exit 0 02:28:16 INFO - Browser initialized. 02:28:16 INFO - Running cycle 1/1 for kraken test... 02:28:16 INFO - TEST-INFO | started process 2057 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpqsKr7B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:28 INFO - PROCESS | 2057 | RSS: Main: 244461568 02:28:28 INFO - PROCESS | 2057 | 02:28:31 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49960/tests/kraken/driver.html?testName=audio-beat-detection) 02:28:32 INFO - PROCESS | 2057 | RSS: Main: 256892928 02:28:32 INFO - PROCESS | 2057 | 02:28:35 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49960/tests/kraken/driver.html?testName=audio-dft) 02:28:35 INFO - PROCESS | 2057 | RSS: Main: 265609216 02:28:35 INFO - PROCESS | 2057 | 02:28:39 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49960/tests/kraken/driver.html?testName=audio-fft) 02:28:39 INFO - PROCESS | 2057 | RSS: Main: 261095424 02:28:39 INFO - PROCESS | 2057 | 02:28:41 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49960/tests/kraken/driver.html?testName=audio-oscillator) 02:28:42 INFO - PROCESS | 2057 | RSS: Main: 262434816 02:28:42 INFO - PROCESS | 2057 | 02:28:44 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49960/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:28:45 INFO - PROCESS | 2057 | RSS: Main: 262811648 02:28:45 INFO - PROCESS | 2057 | 02:28:47 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49960/tests/kraken/driver.html?testName=imaging-darkroom) 02:28:48 INFO - PROCESS | 2057 | RSS: Main: 265048064 02:28:48 INFO - PROCESS | 2057 | 02:28:51 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49960/tests/kraken/driver.html?testName=imaging-desaturate) 02:28:52 INFO - PROCESS | 2057 | RSS: Main: 265154560 02:28:52 INFO - PROCESS | 2057 | 02:28:55 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49960/tests/kraken/driver.html?testName=json-parse-financial) 02:28:55 INFO - PROCESS | 2057 | RSS: Main: 265531392 02:28:55 INFO - PROCESS | 2057 | 02:28:57 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49960/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:28:57 INFO - PROCESS | 2057 | RSS: Main: 264966144 02:28:57 INFO - PROCESS | 2057 | 02:28:59 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:29:00 INFO - PROCESS | 2057 | RSS: Main: 265134080 02:29:00 INFO - PROCESS | 2057 | 02:29:02 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:29:03 INFO - PROCESS | 2057 | RSS: Main: 265502720 02:29:03 INFO - PROCESS | 2057 | 02:29:06 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:29:06 INFO - PROCESS | 2057 | RSS: Main: 265486336 02:29:06 INFO - PROCESS | 2057 | 02:29:09 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:10 INFO - PROCESS | 2057 | RSS: Main: 265850880 02:29:10 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | Cycle 1(1): loaded http://localhost:49960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49960/tests/kraken/driver.html?testName=ai-astar) 02:29:12 INFO - PROCESS | 2057 | RSS: Main: 267886592 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | __start_tp_report 02:29:12 INFO - PROCESS | 2057 | _x_x_mozilla_page_load 02:29:12 INFO - PROCESS | 2057 | _x_x_mozilla_page_load_details 02:29:12 INFO - PROCESS | 2057 | |i|pagename|runs| 02:29:12 INFO - PROCESS | 2057 | |0;ai-astar;107;119;120;145;117;119;117;118;119;137 02:29:12 INFO - PROCESS | 2057 | |1;audio-beat-detection;144;115;134;165;112;151;161;127;160;111 02:29:12 INFO - PROCESS | 2057 | |2;audio-dft;196;180;218;263;283;260;202;206;199;198 02:29:12 INFO - PROCESS | 2057 | |3;audio-fft;65;95;85;160;52;71;85;84;153;71 02:29:12 INFO - PROCESS | 2057 | |4;audio-oscillator;70;74;120;71;69;86;109;76;72;108 02:29:12 INFO - PROCESS | 2057 | |5;imaging-gaussian-blur;90;88;88;89;98;85;90;88;91;85 02:29:12 INFO - PROCESS | 2057 | |6;imaging-darkroom;149;95;93;95;94;113;150;116;94;94 02:29:12 INFO - PROCESS | 2057 | |7;imaging-desaturate;92;95;92;92;102;90;110;144;91;91 02:29:12 INFO - PROCESS | 2057 | |8;json-parse-financial;48;49;83;56;58;55;56;57;56;55 02:29:12 INFO - PROCESS | 2057 | |9;json-stringify-tinderbox;65;66;66;77;73;74;68;68;70;71 02:29:12 INFO - PROCESS | 2057 | |10;stanford-crypto-aes;91;85;89;88;86;84;86;87;83;85 02:29:12 INFO - PROCESS | 2057 | |11;stanford-crypto-ccm;141;141;143;143;144;141;154;142;198;146 02:29:12 INFO - PROCESS | 2057 | |12;stanford-crypto-pbkdf2;184;175;174;173;174;173;169;172;170;226 02:29:12 INFO - PROCESS | 2057 | |13;stanford-crypto-sha256-iterative;69;71;84;90;82;68;70;70;68;70 02:29:12 INFO - PROCESS | 2057 | __end_tp_report 02:29:12 INFO - PROCESS | 2057 | __start_cc_report 02:29:12 INFO - PROCESS | 2057 | _x_x_mozilla_cycle_collect,952 02:29:12 INFO - PROCESS | 2057 | __end_cc_report 02:29:12 INFO - PROCESS | 2057 | __startTimestamp1473499752428__endTimestamp 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | ------- Summary: start ------- 02:29:12 INFO - PROCESS | 2057 | Number of tests: 14 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#0] ai-astar Cycles:10 Average:121.80 Median:119.00 stddev:10.93 (9.2%) stddev-sans-first:10.20 02:29:12 INFO - PROCESS | 2057 | Values: 107.0 119.0 120.0 145.0 117.0 119.0 117.0 118.0 119.0 137.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:147.50 stddev:21.13 (14.3%) stddev-sans-first:22.30 02:29:12 INFO - PROCESS | 2057 | Values: 144.0 115.0 134.0 165.0 112.0 151.0 161.0 127.0 160.0 111.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#2] audio-dft Cycles:10 Average:220.50 Median:212.00 stddev:35.02 (16.5%) stddev-sans-first:36.01 02:29:12 INFO - PROCESS | 2057 | Values: 196.0 180.0 218.0 263.0 283.0 260.0 202.0 206.0 199.0 198.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#3] audio-fft Cycles:10 Average:92.10 Median:77.50 stddev:36.10 (46.6%) stddev-sans-first:36.93 02:29:12 INFO - PROCESS | 2057 | Values: 65.0 95.0 85.0 160.0 52.0 71.0 85.0 84.0 153.0 71.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:71.50 stddev:19.37 (27.1%) stddev-sans-first:19.72 02:29:12 INFO - PROCESS | 2057 | Values: 70.0 74.0 120.0 71.0 69.0 86.0 109.0 76.0 72.0 108.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:89.50 stddev:3.68 (4.1%) stddev-sans-first:3.89 02:29:12 INFO - PROCESS | 2057 | Values: 90.0 88.0 88.0 89.0 98.0 85.0 90.0 88.0 91.0 85.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:94.00 stddev:22.77 (24.2%) stddev-sans-first:19.09 02:29:12 INFO - PROCESS | 2057 | Values: 149.0 95.0 93.0 95.0 94.0 113.0 150.0 116.0 94.0 94.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#7] imaging-desaturate Cycles:10 Average:99.90 Median:91.50 stddev:16.73 (18.3%) stddev-sans-first:17.50 02:29:12 INFO - PROCESS | 2057 | Values: 92.0 95.0 92.0 92.0 102.0 90.0 110.0 144.0 91.0 91.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:56.00 stddev:9.62 (17.2%) stddev-sans-first:9.59 02:29:12 INFO - PROCESS | 2057 | Values: 48.0 49.0 83.0 56.0 58.0 55.0 56.0 57.0 56.0 55.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.50 stddev:3.94 (5.6%) stddev-sans-first:3.77 02:29:12 INFO - PROCESS | 2057 | Values: 65.0 66.0 66.0 77.0 73.0 74.0 68.0 68.0 70.0 71.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.50 stddev:2.41 (2.8%) stddev-sans-first:1.90 02:29:12 INFO - PROCESS | 2057 | Values: 91.0 85.0 89.0 88.0 86.0 84.0 86.0 87.0 83.0 85.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:143.50 stddev:17.55 (12.2%) stddev-sans-first:18.36 02:29:12 INFO - PROCESS | 2057 | Values: 141.0 141.0 143.0 143.0 144.0 141.0 154.0 142.0 198.0 146.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.00 Median:174.00 stddev:17.00 (9.8%) stddev-sans-first:17.94 02:29:12 INFO - PROCESS | 2057 | Values: 184.0 175.0 174.0 173.0 174.0 173.0 169.0 172.0 170.0 226.0 02:29:12 INFO - PROCESS | 2057 | 02:29:12 INFO - PROCESS | 2057 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:70.50 stddev:7.98 (11.3%) stddev-sans-first:8.24 02:29:12 INFO - PROCESS | 2057 | Values: 69.0 71.0 84.0 90.0 82.0 68.0 70.0 70.0 68.0 70.0 02:29:12 INFO - PROCESS | 2057 | -------- Summary: end -------- 02:29:12 INFO - PROCESS | 2057 | 02:29:13 INFO - TEST-INFO | 2057: exit 0 02:29:13 INFO - TEST-OK | kraken | took 59955ms 02:29:13 INFO - SUITE-END | took 1173s 02:29:13 INFO - Completed test suite (00:19:33) 02:29:13 INFO - javascript benchmark 02:29:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7360.310140086431, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2925.3125515503866, 2983.2094494958837, 3418.982905085474, 3424.7773894696843, 3455.8617655293774, 4671.883202919928, 4725.362076119723, 5727.083666613343, 5743.0, 5836.533077353812, 5701.600887937846, 5784.363719990798, 5820.708964551776, 5874.3244526879425, 5924.229850119489, 5230.790768369261, 5253.264542963988, 5280.0, 5301.125314323132, 5370.543503802162, 5297.205419187134, 5322.2548843161985, 5353.170258609902, 5361.919571206435, 5382.865428364283, 5185.740712964337, 5239.476052394768, 5294.549963253128, 5296.766352533325, 5319.521243088121, 4906.386701662293, 5184.351956005499, 5239.056969745444, 5280.392754833205, 5357.491038351351, 4711.7879695413785, 5217.34783152106, 5264.184051472028, 5265.815696450606, 5293.814716484943, 5674.773009079632, 5724.79963201286, 5734.97132514339, 5819.0, 5842.620229685057, 6156.415140561684, 6157.891579515685, 6170.167027451282, 6267.843303917393, 6269.435750782452, 6252.06219067139, 6266.686665666698, 6270.996640537492, 6271.059341098826, 6280.434760871498, 5915.792947246826, 5938.8218353449465, 5953.672548009858, 5978.560125404926, 5997.070454079625, 6119.685352982159, 6141.762184092961, 6225.968870155711, 6234.314225435199, 6288.717007734572, 6165.969170154211, 6223.471004964629, 6238.875222495525, 6241.594296370721, 6255.186825712628, 6224.221972253468, 6261.154744109578, 6272.0, 6272.0, 6279.654618995908, 6119.258838874149, 6154.938450615526, 6178.66017369049, 6185.443310102112, 6192.195014648067, 6216.720247588779, 6216.720247588869, 6230.7819226327765, 6239.532035097386, 6275.874482510324, 4952.4304704959395, 4964.8758781030765, 4974.875628109254, 4983.601311895112, 4994.550490455877, 4411.911761764747, 4433.977830110829, 4441.289393697058, 4514.0971805639, 4514.88712782183, 5084.084864724311, 5376.892462150735, 5377.354717433933, 5403.081476149064, 5436.238926550286, 6167.876642467126, 6222.471089957314, 6223.719932603044, 6226.6575338355915, 6271.0, 4604.871806407452, 5286.995470860525, 5351.117065684196, 5354.330708661418, 5388.353397592314, 4404.6256068233915, 5278.9472105278455, 5308.893822123536, 5316.308879845596, 5361.865953351197, 6350.333215012357, 6365.96817015912, 6377.107204991212, 6388.361163883575, 6398.936010639834, 6101.065651476929, 6183.288921774054, 6218.844528886814, 6223.502119830493, 6252.562320637512, 6232.283287421823, 6295.150154729053, 6308.968455157695, 6332.873342533031, 6384.265809431789, 6234.37656234391, 6237.376262373727, 6243.937560624518, 6247.468965137832, 6275.811725648238, 5942.58401911862, 6177.980633195472, 6236.594621349779, 6239.656818875005, 6246.1255424239735, 5995.760169593167, 6023.66869822164, 6090.756369745161, 6108.584917381041, 6153.969230153821, 6133.579501509479, 6170.074488826712, 6191.349908259568, 6203.286622038523, 6222.097795819672, 5957.642541447527, 6042.123892035543, 6055.515558755201, 6121.0, 6123.4488895999575, 6108.797423689751, 6163.722632481459, 6195.380461953769, 6197.163382943425, 6245.250569931637, 6074.878502430015, 6097.420745029215, 6108.784113181727, 6110.533471966785, 6112.5679851514815], "value": 5685.355863490198, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.1571228178773, 534.9438308977557, 539.9406065332813, 544.6459801129268, 558.391353424767, 40050.0, 41812.790936045276, 41818.37272440912, 41879.37180942281, 41895.58104418955, 42367.78816105923, 42490.0, 42780.786096069554, 42808.0, 42938.0, 9946.25403094769, 10096.0, 10132.14978308242, 10135.0, 10157.796844063114, 9851.802963940718, 9915.405075695446, 9940.453275069869, 9950.751231219241, 9966.501674916262, 9942.154916832078, 9951.0, 9981.75045623858, 9983.40099594023, 9999.10008099271, 9892.0, 9905.603775848993, 9924.45415502147, 9954.601815927317, 10016.399016058987, 9910.256730745223, 9987.600495980152, 10015.69952901414, 10021.79956400868, 10024.348417352921, 41172.38241426381, 41234.587654123374, 41460.37809432861, 41539.961500962716, 41581.376279355834, 2942.8528573571234, 3038.8176709397508, 3551.0944709099012, 3591.0, 3721.8697345592773, 31054.844725776227, 31082.844585776926, 31352.21619459509, 31465.0, 32231.355372892413, 2752.775015118273, 3240.6111266648154, 3246.0586429935524, 3259.445894197992, 3369.393509168349, 500880.0, 503462.0, 504026.0, 505265.0, 507970.0, 57300.71349643308, 57717.422825772046, 57779.13331300034, 57862.7106864463, 57882.710586446796, 34325.828370858486, 34593.308133837185, 34781.826090869385, 36264.27471450609, 60861.0, 16489.422870199487, 16640.583985400463, 16641.251143698562, 16650.583735406468, 16674.916625416794, 12602.243865368107, 12726.681832954066, 12830.230186188859, 12831.294278814756, 12852.807207891889, 18696.439106826816, 18806.90596547036, 18816.529586760167, 18833.0, 18882.055897205086, 7615.504992175491, 7774.416918731118, 7939.245771651684, 8005.639746211435, 8027.5584842834205, 834.1783343406739, 896.251629889037, 926.2404828040942, 957.0525180071679, 1053.9262251642313, 28755.856220719183, 30017.94937177232, 30040.54939175914, 30148.0, 30606.234844129074, 1023.181454836143, 1053.0575135254057, 1116.263266244275, 1124.6738445850613, 1135.5628083187867, 12846.522649895194, 13918.0, 15428.922855385652, 15518.0, 15609.843901560838, 713550.0, 716561.0, 720902.0, 723586.0, 724250.0, 56158.71920640452, 57354.426455735746, 57638.71180644154, 57654.0, 57677.0, 1348.0900392235399, 1382.315753701904, 1396.4833011785906, 1398.6643205630576, 1400.6008287637974, 28493.0, 29400.852995734884, 29450.852745736134, 29678.109656710334, 30294.394112118076, 46261.07477850492, 49076.773080673265, 49134.0, 49203.26195107148, 49206.75396622994, 617619.0, 623903.0, 623958.0, 624511.0, 628447.0, 22027.00878460441, 25378.0, 25775.6133657999, 26206.737932621192, 26297.868510657325, 44408.77795611001, 44752.77623611861, 45322.77338613286, 45501.54498455105, 45611.0, 36390.818045909604, 38176.236475270896, 38214.0, 39037.0, 39685.80942571728, 37748.2450350986, 38108.61891381162, 38996.025099372746, 39447.802760986015, 39643.80178099091, 36630.8168459156, 37058.444123337635, 37953.48186072526, 38355.849324520306, 39038.6096139035, 27441.86279068672, 29081.0, 30075.548866766578, 32973.17567060843, 33196.83401583073, 25249.87375063113, 25365.0, 25583.0, 25964.74035259699, 26103.738962610132], "value": 19881.124430110067, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.88903329001295, 371.7657875538409, 376.43346763121497, 378.10766590845583, 401.63250625677506, 18983.145758440867, 20245.797542024575, 20514.794852051476, 20531.589368212626, 20844.0, 17819.554511137194, 17903.820961790378, 18193.909030454863, 20443.080061397235, 20943.58112837746, 5970.432808883149, 6017.819465416044, 6155.907661385083, 6165.537584681145, 6258.436740693337, 4200.138971510848, 5359.330083739533, 6069.0, 6171.0, 6189.690515474208, 4367.934480982788, 5723.885522289552, 6457.096006559085, 6808.2510923798345, 6861.073755043055, 5768.961586914354, 6593.93406065938, 6834.9658251708925, 6859.897101543481, 6882.070920425728, 5958.97020514899, 5961.552883533753, 5967.850803729942, 5977.923973684735, 5979.342272350038, 26011.0, 26204.475910481684, 26206.0, 26390.812413441443, 26670.5999410009, 2212.0598745533243, 2228.6099932511747, 2328.231683544443, 2661.614065960425, 2860.156253905103, 15518.61203469911, 15745.921270393575, 15949.202539873077, 16042.43851465193, 16632.916835415745, 1672.247488630109, 1700.1499250374814, 1886.76415448069, 1897.5635603811202, 1912.8469722422174, 119381.40309298398, 124970.37514812459, 139083.0, 140346.38614455308, 142599.0, 11259.662210133709, 11332.490037948148, 11513.021393181505, 11532.019778318769, 11726.413679316, 14553.816927460966, 14571.0, 14574.781378279122, 14608.707825843423, 14614.561563153122, 7158.713651453883, 8312.750617481484, 8484.024337201177, 8540.487570745776, 8640.654373824977, 8453.281471074904, 8495.66017359311, 8521.233089022018, 8645.351598630003, 8681.435706679173, 4432.246518091933, 4479.955200447954, 4533.229351010336, 4577.908441831145, 4599.632029437704, 1580.162513867652, 1880.9153588088568, 1917.893013318433, 1924.70167124096, 1927.1038966880467, 908.8227795579799, 914.4063829151366, 940.6284517615503, 944.2823454174877, 957.674390707149, 21768.0, 22123.889380553315, 22477.29124694407, 22505.774942250697, 22741.090356385892, 925.5464822237055, 934.2852717670987, 955.9091886270793, 964.8070385922844, 1028.5063169678447, 9816.852747208797, 11279.436028198557, 11555.711107222218, 12204.633860984184, 12239.510419583296, 527161.0, 527208.0, 632085.0, 638673.0, 643097.0, 137589.3120534391, 141176.0, 146667.0, 149692.0, 153648.0, 425.5446672060865, 441.6180004296261, 446.1923917708957, 446.49546013005096, 450.36498537062585, 9115.27077833785, 9749.317547771589, 10202.438865862448, 11120.721981950515, 11791.469383877575, 53878.73060634828, 55989.0, 56624.716876415354, 58846.82306353935, 59290.40709592849, 64343.0, 72043.0, 73337.0, 82798.17201827905, 86883.26233475421, 8124.39067069984, 8169.7745338197365, 8327.333813294801, 8526.488410695378, 8540.487570745776, 12189.75620487603, 12437.253764774143, 13155.079144459796, 13336.733010364, 13520.0, 9630.0, 11052.723681908017, 11345.716357091138, 11426.743058263419, 11480.655580332603, 11056.613018544313, 11217.158713096387, 11262.04272636835, 11279.266847654877, 11662.53349865996, 11413.60052398162, 11416.0, 11472.31166130035, 11775.882241177822, 11797.35114568707, 7866.372893467389, 7954.528412243753, 8019.679212831421, 8077.676892924453, 8186.222308880647, 8121.675132994614, 10627.36235825853, 11164.832527512257, 11348.659540213806, 11712.355820429959], "value": 9490.744463099283, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.1490535489413, 1516.8710659593935, 1736.800267969184, 1878.8872667639935, 1892.4228110426322, 8137.18628137186, 8440.704575339865, 8613.0, 9179.311551633638, 9210.217131543819, 8592.441491303061, 8935.91064089359, 8939.106089391058, 9036.95481522593, 9040.276777857773, 2382.1900553811656, 2421.842580232288, 2422.648715936188, 2430.647556104364, 2430.7083150021936, 2378.726446458662, 2408.89159987801, 2423.0307876849224, 2433.3916520869784, 2434.6834911461483, 2356.9982757328153, 2398.8440751351104, 2403.6875206223167, 2419.2742177346813, 2423.987880060597, 2113.92601258956, 2376.1208352909534, 2394.1740099665676, 2401.5677178107935, 2403.447207142367, 2370.111208296889, 2388.725296590897, 2398.1126983016397, 2399.7360290368047, 2400.543896659629, 7627.580483073429, 8977.0, 9080.954595227047, 9111.954440227822, 9130.360874738772, 5284.365876094855, 5437.537809286216, 5441.945580544183, 5449.56403487724, 5458.2904222451225, 3633.709303255733, 3637.1452708613456, 3679.356112680297, 3699.5005674233903, 3718.9070273243115, 1081.1783044885942, 1083.1713738989679, 1089.6458650938398, 1099.6646022962993, 1102.3441052573705, 11891.881081189164, 11999.460024298927, 12076.033917286595, 12077.939610301892, 12087.758244835142, 4508.571685689854, 4692.343071969927, 4780.904381912343, 4882.951170488321, 4885.902281954341, 10160.745981350525, 10181.440020798927, 10241.07830295277, 10252.179825614083, 10255.487225638688, 5375.704336261465, 5382.91925621116, 5387.838364849061, 5389.487998640123, 5410.296661434067, 4443.488998765119, 4604.263317869125, 4806.255030470283, 4927.457979622239, 4941.703497790144, 3830.7701537907815, 3948.980255098706, 3963.4847469829315, 4081.959180408158, 4126.855560055384, 1127.8421021057059, 1134.5915470430639, 1161.233585833363, 1172.0916289875245, 1340.8390993080893, 777.5995362388375, 796.665400531778, 839.8068444257855, 925.4956048953337, 957.2868213181224, 5798.33319168301, 5951.732172052181, 6015.428534289235, 6132.264128304632, 6309.305976342599, 448.717308095904, 454.22101096619457, 456.88121088517227, 465.4344970860353, 467.0215897694325, 3323.93352132956, 3449.275652113034, 4030.9395359069636, 4100.118474527991, 4147.357159640261, 10087.193024558017, 10333.689989300334, 10498.10766084876, 10743.570257189782, 11032.007119359296, 11065.948734870175, 11107.888921110847, 11109.111271098292, 11116.999470047573, 11145.554177832959, 7416.887466880011, 7915.762527124196, 8131.796705082302, 8302.252797248277, 8359.456635318686, 5895.351511333835, 5938.554608404474, 5977.760889564369, 6031.065184896348, 6032.607880487754, 10267.589296428358, 10362.585496580354, 10364.378137311785, 10388.584456621651, 10437.0, 9833.311668183158, 10321.174306055647, 10350.585976560853, 10468.947655261674, 10495.737606559896, 7036.859262814817, 7230.0, 7281.162666293444, 7421.035265415461, 7726.536407815549, 8349.37379696513, 8634.395592308729, 8651.394402391772, 8651.567421629019, 8665.09016553278, 7591.962040189764, 7731.265529774662, 7761.72833950809, 7831.451798374287, 7859.724909628136, 7444.143923448656, 7574.962125189339, 7598.088229412285, 7612.429067820047, 7633.580153091633, 6989.0, 7095.254998225112, 7095.538789978635, 7102.431805455654, 7111.82220444493, 4925.556699896883, 4988.850334489971, 5020.698758074527, 5064.721440320818, 5184.66299690534, 5046.318746969112, 5059.392872855281, 5087.491250874883, 5168.870778230575, 5335.0], "value": 4557.004590662375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.8181273108824, 282.7935607006885, 292.9853507324634, 308.29400672460054, 310.38853458686395, 5354.196870469432, 5496.4503549645115, 5620.803271885485, 5652.293463317091, 5673.177389278558, 4484.708493947891, 4486.6410687145035, 4774.522547745224, 5483.7532311046, 5537.446255374461, 3592.0121966459174, 3628.095249993761, 3934.960650393488, 4085.9387109193385, 4218.493780746315, 4145.922060264338, 4163.791810409482, 4181.811818468176, 4182.91634167318, 4188.246115699173, 3713.1459764254237, 4040.7373520721058, 4151.169766046803, 4161.542230354658, 4171.541130475645, 4003.768949577973, 4071.4503542021744, 4072.7556346619003, 4124.752514849119, 4153.8961525961795, 2993.760499160062, 3570.3037907608027, 3572.7677700949357, 3575.5340310472843, 3587.4260118381194, 5272.710000949946, 5310.070737620939, 5311.468853114697, 5318.526884998456, 5354.705491197982, 3637.854485820564, 4682.08034705769, 4696.718196908197, 4772.618190544783, 4779.163646361872, 5449.457852395934, 5618.353889302701, 5712.657525481509, 5734.713264336809, 5755.798547050833, 3953.723239373245, 3959.8020098994934, 3961.4057891316247, 3966.1869316790276, 4003.179348233605, 4779.641526885497, 5622.690752008638, 5955.612885162451, 5996.370381109964, 6068.605540639845, 3905.2384784966657, 3906.570277269498, 3910.4720862683744, 3916.7845768483007, 3932.528096628423, 3928.273269445162, 3987.8803635890968, 4006.399040144002, 4028.8589899353383, 4028.8992775180855, 4727.550882666142, 4730.858074257778, 4739.123262196505, 4751.287306903992, 4774.522547745267, 5553.666779993213, 5853.2976042875125, 5890.615705309249, 5934.851628709317, 5939.2575928009, 1305.2103477396229, 1311.6883116883116, 1380.7297286496448, 1395.8115654386527, 1405.641561401836, 772.1506343022743, 787.8188016756178, 827.0530242871998, 834.7913021744564, 891.5319457284977, 4137.586241375839, 4484.506704262529, 4648.163330600524, 4979.576735977483, 5124.5131712487255, 4492.483364413134, 5456.181572764038, 5719.685417302005, 5843.28287346386, 5942.673152976541, 3787.4739686074877, 4674.672772905932, 5080.298100136496, 5134.895997360585, 5178.689278643294, 3063.234191452137, 3103.1466346754823, 3104.580881580958, 3116.6415862175686, 3126.8436578171454, 5234.110201265794, 5933.673647949404, 5939.70301484924, 6154.7230374633245, 6277.591956522902, 5109.262850630804, 5564.219449776114, 6044.879102417927, 6103.755849765959, 6181.721822517998, 5166.9224961625605, 6103.572749907553, 6125.418085281891, 6147.477464415396, 6167.198264225712, 5623.325200975745, 5909.674967876808, 5929.555283353679, 5943.494802941798, 6016.112266112378, 5308.920366194433, 5368.7852485901685, 5877.911831322619, 5918.260217472816, 5974.970125149346, 5368.4631536846, 5437.619366644297, 6084.0, 6160.815175544741, 6168.629882207082, 3416.164583395825, 3441.913952151216, 3456.084137703561, 3664.2121943782213, 3674.0, 5047.899042019066, 5657.0, 5973.223480947449, 5973.522118230444, 5985.940140598538, 5344.893102138013, 5858.560607954335, 5876.647401155944, 5885.499732522783, 5894.557908156991, 4807.591354734747, 5531.861703457445, 5664.461876121762, 5790.9420905792085, 5797.710114494343, 4823.903521929612, 5729.169270455844, 5758.424157584208, 5772.913406298825, 5804.73878675469, 4695.812167513398, 5517.972410137923, 5646.322441307069, 5717.056685646821, 5854.941450585439, 5696.974544582015, 5718.599698021265, 5738.770449181986, 5761.567882408753, 5764.855878602901], "value": 4157.393926176874, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.923134608174, 696.6899729620314, 709.8970649255862, 794.841031793641, 804.0793291681027, 15243.390264389425, 15916.442924497645, 15921.0, 15927.362905483767, 15942.963707359027, 13959.371828267727, 14084.36620352084, 14252.572422827332, 14355.354009069591, 14404.927975360108, 686.2965460403077, 700.7687463137178, 856.1395797223781, 867.7440155154245, 878.4641368765081, 876.1545108969843, 885.5749240364629, 885.8405487012335, 889.799795046116, 890.2699786175339, 913.127962795532, 932.8600709893536, 938.7700013496669, 945.744648944788, 946.1201082992814, 922.3174850610534, 940.2947789158166, 942.3356533643764, 943.0286804591248, 946.186279799372, 697.3723648716145, 702.301210295759, 703.6481759120439, 705.7988473285089, 705.9611721355324, 14403.207823569699, 14685.55943321702, 14697.706045879022, 14703.0, 14704.485343012942, 14580.562583122522, 14623.561293161221, 14652.633684157981, 14678.92660536701, 14829.629259268497, 11752.588659396966, 11876.465559049864, 11960.46177921987, 11987.0, 12028.613444330882, 3648.908777280563, 3810.3903375460072, 3833.961660383416, 3942.8817135485983, 3945.842166313344, 78104.43791124203, 94511.52744236303, 98679.0, 98969.50515247449, 100615.0, 10450.268481206394, 10648.041676249175, 10754.139668826476, 10790.082842958436, 10792.730181745363, 20878.686819697716, 21089.367318980454, 21106.894465527883, 21130.577388452144, 21151.471213219484, 7784.4940078895825, 7961.402894782915, 7966.123726390092, 8235.176482351837, 8300.252977232078, 4439.889002774957, 4524.23088075028, 4547.47704014036, 4554.3851580036935, 4561.133384656904, 3662.1760103976394, 3775.6601905828056, 3786.67813235878, 3803.2583646188727, 3815.351390263662, 679.2290749998787, 703.5286358140058, 703.9964800175966, 708.6704682322745, 715.6028404235595, 583.291301069203, 650.8926027205458, 663.1743479368217, 663.6018388966581, 682.7030241844814, 8368.497890126611, 8907.73276801684, 8908.465492070496, 9373.484458354857, 9798.608055677692, 618.7184830901929, 640.4012248547623, 676.7123972311748, 680.6052489556046, 681.2778454837887, 3706.6478684524927, 4404.867853964386, 4733.786979585927, 4832.9275060874115, 4878.0, 125694.0, 140277.8958315626, 142391.0, 156830.0, 178031.0, 96477.0, 107937.92062079436, 109407.45296273468, 118239.8176018246, 121184.0, 103007.0, 103306.0, 105238.0, 106524.0, 107794.0, 9074.954625226832, 9835.114839664464, 9946.45294508809, 9977.750556236153, 10101.725694842708, 39998.0, 46692.0, 51971.0, 52730.0, 54094.93018089614, 86852.26295474182, 92770.0, 93146.0, 93207.53396232975, 97933.0, 13908.304584770518, 14030.438782448997, 14068.577942661737, 14122.929385353007, 14782.852171478147, 13846.723065538834, 14331.85668143347, 15031.022983506036, 15396.923015385299, 15753.921230393775, 13897.791533126809, 13917.860821391656, 14390.85609143895, 14614.70770584561, 14625.707485850437, 13895.722085558435, 14150.009499334948, 14239.857601423853, 14379.0, 14466.782998255245, 13783.448662053406, 13912.373943172784, 13970.0, 14118.50585229512, 14405.279736012948, 9971.601135954481, 11398.0, 11634.476448560004, 12049.156559041134, 12248.632541023784, 9640.42157470554, 9986.80817892911, 10744.579645466614, 11726.296422214695, 11885.28688278706], "value": 7822.254275858537, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1572.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 120.0, 145.0, 117.0, 119.0, 117.0, 118.0, 119.0, 137.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 134.0, 165.0, 112.0, 151.0, 161.0, 127.0, 160.0, 111.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 180.0, 218.0, 263.0, 283.0, 260.0, 202.0, 206.0, 199.0, 198.0], "value": 220.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 95.0, 85.0, 160.0, 52.0, 71.0, 85.0, 84.0, 153.0, 71.0], "value": 92.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 120.0, 71.0, 69.0, 86.0, 109.0, 76.0, 72.0, 108.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 88.0, 89.0, 98.0, 85.0, 90.0, 88.0, 91.0, 85.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 95.0, 93.0, 95.0, 94.0, 113.0, 150.0, 116.0, 94.0, 94.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 92.0, 92.0, 102.0, 90.0, 110.0, 144.0, 91.0, 91.0], "value": 99.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 83.0, 56.0, 58.0, 55.0, 56.0, 57.0, 56.0, 55.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 77.0, 73.0, 74.0, 68.0, 68.0, 70.0, 71.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 85.0, 89.0, 88.0, 86.0, 84.0, 86.0, 87.0, 83.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 143.0, 143.0, 144.0, 141.0, 154.0, 142.0, 198.0, 146.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 175.0, 174.0, 173.0, 174.0, 173.0, 169.0, 172.0, 170.0, 226.0], "value": 179.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 84.0, 90.0, 82.0, 68.0, 70.0, 70.0, 68.0, 70.0], "value": 74.2, "unit": "score"}]}]} 02:29:13 INFO - Return code: 0 02:29:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:13 ERROR - # TBPL SUCCESS # 02:29:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:13 INFO - 2016-09-10 02:29:13.737 screenresolution[2062:17945] starting screenresolution argv=screenresolution get 02:29:13 INFO - 2016-09-10 02:29:13.739 screenresolution[2062:17945] Display 0: 1600x1200x32@60 02:29:13 INFO - 2016-09-10 02:29:13.745 screenresolution[2063:17948] starting screenresolution argv=screenresolution list 02:29:13 INFO - Available Modes on Display 0 02:29:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:13 INFO - Intel Iris: 02:29:13 INFO - Chipset Model: Intel Iris 02:29:13 INFO - Type: GPU 02:29:13 INFO - Bus: Built-In 02:29:13 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:13 INFO - Vendor: Intel (0x8086) 02:29:13 INFO - Device ID: 0x0a2e 02:29:13 INFO - Revision ID: 0x0009 02:29:13 INFO - Displays: 02:29:13 INFO - Display: 02:29:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:13 INFO - Display Serial Number: 1600x1200 60 02:29:13 INFO - Main Display: Yes 02:29:13 INFO - Mirror: Off 02:29:13 INFO - Online: Yes 02:29:13 INFO - Rotation: Supported 02:29:13 INFO - Return code: 0 02:29:13 INFO - Running post-action listener: _resource_record_post_action 02:29:13 INFO - [mozharness: 2016-09-10 09:29:13.984121Z] Finished run-tests step (success) 02:29:13 INFO - Running post-run listener: _resource_record_post_run 02:29:14 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 1753088; Write bytes: 708300288; Read time: 22; Write time: 2050 02:29:14 INFO - TinderboxPrint: CPU usage
54.7% 02:29:14 INFO - TinderboxPrint: I/O read bytes / time
1,753,088 / 22 02:29:14 INFO - TinderboxPrint: I/O write bytes / time
708,300,288 / 2,050 02:29:14 INFO - TinderboxPrint: CPU idle
2,190.4 (45.3%) 02:29:14 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 02:29:14 INFO - TinderboxPrint: CPU user
1,665.2 (34.4%) 02:29:14 INFO - TinderboxPrint: Swap in / out
407,134,208 / 0 02:29:14 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402608640; Write bytes: 397207552; Read time: 29808; Write time: 842 02:29:14 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 1712128; Write bytes: 298341888; Read time: 20; Write time: 1165 02:29:14 INFO - Running post-run listener: _upload_blobber_files 02:29:14 INFO - Blob upload gear active. 02:29:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:14 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:29:14 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:29:14 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:29:14 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:29:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:29:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:15 INFO - (blobuploader) - INFO - Done attempting. 02:29:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:29:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:16 INFO - (blobuploader) - INFO - Done attempting. 02:29:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:19 INFO - (blobuploader) - INFO - Done attempting. 02:29:19 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:19 INFO - Return code: 0 02:29:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a38d47cfe62c8ee49d129de40c77242d8ffc3da804b3b722adc8892858db1600dbc2182633990e2a83bba6e776fc99a38a4d3552ad63cc3ed7f1fb68468c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7716b3907f7bccf2b211f53e7b0624d26d886d606d9a32320b2483d5e7d06b868fcf21cd89f1a794d0d92d6db41c56b32e72205176dacc38d97a707fbefef596", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e8f32b044aa4348cc84695aaf3b10d2f33871022118150ad9396902db017115d376a2ffa8b2993b96f9e79f16c6783b732e0ee4d03c658bc6135de1620230b"} 02:29:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:19 INFO - Contents: 02:29:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a38d47cfe62c8ee49d129de40c77242d8ffc3da804b3b722adc8892858db1600dbc2182633990e2a83bba6e776fc99a38a4d3552ad63cc3ed7f1fb68468c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7716b3907f7bccf2b211f53e7b0624d26d886d606d9a32320b2483d5e7d06b868fcf21cd89f1a794d0d92d6db41c56b32e72205176dacc38d97a707fbefef596", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e8f32b044aa4348cc84695aaf3b10d2f33871022118150ad9396902db017115d376a2ffa8b2993b96f9e79f16c6783b732e0ee4d03c658bc6135de1620230b"} 02:29:19 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:19 INFO - Copying logs to upload dir... 02:29:19 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.103865 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-10 02:29:19.203681) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 02:29:19.207668) ========= 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.mScJaB2P0S/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.MHCpOKSAGA/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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/56a38d47cfe62c8ee49d129de40c77242d8ffc3da804b3b722adc8892858db1600dbc2182633990e2a83bba6e776fc99a38a4d3552ad63cc3ed7f1fb68468c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7716b3907f7bccf2b211f53e7b0624d26d886d606d9a32320b2483d5e7d06b868fcf21cd89f1a794d0d92d6db41c56b32e72205176dacc38d97a707fbefef596", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e8f32b044aa4348cc84695aaf3b10d2f33871022118150ad9396902db017115d376a2ffa8b2993b96f9e79f16c6783b732e0ee4d03c658bc6135de1620230b"} build_url:https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/LbpnfioXTpKLf5fh2Tbpog/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56a38d47cfe62c8ee49d129de40c77242d8ffc3da804b3b722adc8892858db1600dbc2182633990e2a83bba6e776fc99a38a4d3552ad63cc3ed7f1fb68468c18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7716b3907f7bccf2b211f53e7b0624d26d886d606d9a32320b2483d5e7d06b868fcf21cd89f1a794d0d92d6db41c56b32e72205176dacc38d97a707fbefef596", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55e8f32b044aa4348cc84695aaf3b10d2f33871022118150ad9396902db017115d376a2ffa8b2993b96f9e79f16c6783b732e0ee4d03c658bc6135de1620230b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-10 02:29:19.241465) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:29:19.241921) ========= 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.mScJaB2P0S/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.MHCpOKSAGA/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.004516 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-10 02:29:19.290548) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 02:29:19.290859) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-10 02:29:19.291194) ========= ========= Total master_lag: 0.21 =========