builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0272 starttime: 1473327850.66 results: success (0) buildid: 20160907175716 builduid: 0aad801a4896498f96ec88398c84536c revision: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.664918) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.665268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.665556) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.740481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.741158) ========= 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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-08 02:44:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-09-08 02:44:10 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172555 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.947430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:10.948168) ========= 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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.027353 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.001421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.002240) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 --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 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-08 02:44:11,091 truncating revision to first 12 chars 2016-09-08 02:44:11,092 Setting DEBUG logging. 2016-09-08 02:44:11,092 attempt 1/10 2016-09-08 02:44:11,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/77940cbf0c2a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-08 02:44:11,760 unpacking tar archive at: mozilla-central-77940cbf0c2a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.938620 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.971501) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.971817) ========= script_repo_revision: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.972232) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.972559) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-08 02:44:11.997811) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-08 02:44:11.998139) ========= /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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:12 INFO - MultiFileLogger online at 20160908 02:44:12 in /builds/slave/test 02:44:12 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:44:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:12 INFO - {'append_to_log': False, 02:44:12 INFO - 'base_work_dir': '/builds/slave/test', 02:44:12 INFO - 'blob_upload_branch': 'Firefox', 02:44:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:12 INFO - 'branch': 'Firefox', 02:44:12 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:12 INFO - 'config_files': ('talos/mac_config.py',), 02:44:12 INFO - 'default_actions': ('clobber', 02:44:12 INFO - 'read-buildbot-config', 02:44:12 INFO - 'download-and-extract', 02:44:12 INFO - 'populate-webroot', 02:44:12 INFO - 'create-virtualenv', 02:44:12 INFO - 'install', 02:44:12 INFO - 'run-tests'), 02:44:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:12 INFO - 'download_minidump_stackwalk': True, 02:44:12 INFO - 'download_symbols': 'ondemand', 02:44:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:44:12 INFO - 'tooltool.py': '/tools/tooltool.py', 02:44:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:44:12 INFO - '/tools/misc-python/virtualenv.py')}, 02:44:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:12 INFO - 'installer_path': 'installer.exe', 02:44:12 INFO - 'log_level': 'info', 02:44:12 INFO - 'log_name': 'talos', 02:44:12 INFO - 'log_to_console': True, 02:44:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:44:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:44:12 INFO - 'opt_config_files': (), 02:44:12 INFO - 'pip_index': False, 02:44:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:44:12 INFO - 'cmd': ('bash', 02:44:12 INFO - '-c', 02:44:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:44:12 INFO - 'enabled': True, 02:44:12 INFO - 'halt_on_failure': False, 02:44:12 INFO - 'name': 'check_screen_resolution'},), 02:44:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:44:12 INFO - 'cmd': ('bash', 02:44:12 INFO - '-c', 02:44:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:44:12 INFO - 'enabled': True, 02:44:12 INFO - 'halt_on_failure': False, 02:44:12 INFO - 'name': 'check_screen_resolution'},), 02:44:12 INFO - 'run_cmd_checks_enabled': True, 02:44:12 INFO - 'sps_profile': False, 02:44:12 INFO - 'sps_profile_interval': 0, 02:44:12 INFO - 'suite': 'dromaeojs-e10s', 02:44:12 INFO - 'system_bits': '32', 02:44:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:12 INFO - 'title': 't-yosemite-r7-0272', 02:44:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:12 INFO - 'use_talos_json': True, 02:44:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:12 INFO - 'work_dir': 'build'} 02:44:12 INFO - [mozharness: 2016-09-08 09:44:12.201057Z] Running clobber step. 02:44:12 INFO - Running pre-action listener: _resource_record_pre_action 02:44:12 INFO - Running main action method: clobber 02:44:12 INFO - rmtree: /builds/slave/test/build 02:44:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:14 INFO - Running post-action listener: _resource_record_post_action 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.116289Z] Finished clobber step (success) 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.116421Z] Running read-buildbot-config step. 02:44:14 INFO - Running pre-action listener: _resource_record_pre_action 02:44:14 INFO - Running main action method: read_buildbot_config 02:44:14 INFO - Using buildbot properties: 02:44:14 INFO - { 02:44:14 INFO - "project": "", 02:44:14 INFO - "product": "firefox", 02:44:14 INFO - "script_repo_revision": "production", 02:44:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:44:14 INFO - "repository": "", 02:44:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:44:14 INFO - "buildid": "20160907175716", 02:44:14 INFO - "pgo_build": "False", 02:44:14 INFO - "basedir": "/builds/slave/test", 02:44:14 INFO - "buildnumber": 31, 02:44:14 INFO - "slavename": "t-yosemite-r7-0272", 02:44:14 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:44:14 INFO - "platform": "yosemite_r7", 02:44:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:44:14 INFO - "branch": "mozilla-central", 02:44:14 INFO - "repo_path": "mozilla-central", 02:44:14 INFO - "revision": "77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501", 02:44:14 INFO - "stage_platform": "macosx64", 02:44:14 INFO - "builduid": "0aad801a4896498f96ec88398c84536c", 02:44:14 INFO - "slavebuilddir": "test" 02:44:14 INFO - } 02:44:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:44:14 INFO - Running post-action listener: _resource_record_post_action 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.119812Z] Finished read-buildbot-config step (success) 02:44:14 INFO - [mozharness: 2016-09-08 09:44:14.119923Z] Running download-and-extract step. 02:44:14 INFO - Running pre-action listener: _resource_record_pre_action 02:44:14 INFO - Running main action method: download_and_extract 02:44:14 INFO - mkdir: /builds/slave/test/build/tests 02:44:14 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:44:14 INFO - trying https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:44:14 INFO - Downloading https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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:44:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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:44:15 INFO - Downloaded 1199 bytes. 02:44:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:44:15 INFO - Using the following test package requirements: 02:44:15 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:44:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:44:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:44:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'jsshell-mac.zip'], 02:44:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:44:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:44:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:44:15 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:44:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:44:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:44:15 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:44:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:44:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:44:15 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:22 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:44:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:44:23 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:44:29 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 02:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:30 INFO - trying https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:30 INFO - Downloading https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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:44:35 INFO - Downloaded 111456518 bytes. 02:44:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:35 INFO - mkdir: /builds/slave/test/properties 02:44:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:35 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:35 INFO - Contents: 02:44:35 INFO - build_url:https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:44:36 INFO - Running post-action listener: _resource_record_post_action 02:44:36 INFO - Running post-action listener: set_extra_try_arguments 02:44:36 INFO - [mozharness: 2016-09-08 09:44:36.018104Z] Finished download-and-extract step (success) 02:44:36 INFO - [mozharness: 2016-09-08 09:44:36.018267Z] Running populate-webroot step. 02:44:36 INFO - Running pre-action listener: _resource_record_pre_action 02:44:36 INFO - Running main action method: populate_webroot 02:44:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:44:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:44:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:44:36 INFO - '--tppagecycles', 02:44:36 INFO - '7'], 02:44:36 INFO - 'tests': ['tsvgm']}}, 02:44:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['damp', 'tps']}, 02:44:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'tests': ['damp', 'tps']}, 02:44:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['dromaeo_dom']}, 02:44:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['basic_compositor_video']}, 02:44:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:44:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['a11yr', 02:44:36 INFO - 'ts_paint', 02:44:36 INFO - 'tpaint', 02:44:36 INFO - 'sessionrestore', 02:44:36 INFO - 'sessionrestore_no_auto_restore', 02:44:36 INFO - 'tabpaint']}, 02:44:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['tsvgx', 02:44:36 INFO - 'tsvgr_opacity', 02:44:36 INFO - 'tart', 02:44:36 INFO - 'tscrollx', 02:44:36 INFO - 'cart']}, 02:44:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:36 INFO - 'tsvgr_opacity', 02:44:36 INFO - 'tart', 02:44:36 INFO - 'tscrollx', 02:44:36 INFO - 'cart']}, 02:44:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'talos_options': ['--disable-e10s'], 02:44:36 INFO - 'tests': ['tp5o']}, 02:44:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'tests': ['tp5o']}, 02:44:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'talos_options': ['--disable-e10s', 02:44:36 INFO - '--xperf_path', 02:44:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:36 INFO - 'tests': ['tp5n']}, 02:44:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:44:36 INFO - 'talos_options': ['--xperf_path', 02:44:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:36 INFO - 'tests': ['tp5n']}}, 02:44:36 INFO - 'talos.zip': {'path': '', 02:44:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:44:36 INFO - Running post-action listener: _resource_record_post_action 02:44:36 INFO - [mozharness: 2016-09-08 09:44:36.037220Z] Finished populate-webroot step (success) 02:44:36 INFO - [mozharness: 2016-09-08 09:44:36.037333Z] Running create-virtualenv step. 02:44:36 INFO - Running pre-action listener: _resource_record_pre_action 02:44:36 INFO - Running main action method: create_virtualenv 02:44:36 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:36 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:44:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:44:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:36 INFO - Using real prefix '/tools/python27' 02:44:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:36 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:39 INFO - Installing pip.................done. 02:44:39 INFO - Return code: 0 02:44:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44: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:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44: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:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:39 INFO - 'HOME': '/Users/cltbld', 02:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:39 INFO - 'LOGNAME': 'cltbld', 02:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:39 INFO - 'MOZ_NO_REMOTE': '1', 02:44:39 INFO - 'NO_EM_RESTART': '1', 02:44:39 INFO - 'PAGER': '/bin/cat', 02:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:39 INFO - 'PWD': '/builds/slave/test', 02:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:39 INFO - 'SHELL': '/bin/bash', 02:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:39 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:39 INFO - 'USER': 'cltbld', 02:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:39 INFO - 'XPC_FLAGS': '0x0', 02:44:39 INFO - 'XPC_SERVICE_NAME': '0', 02:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:40 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:44:40 INFO - Cleaning up... 02:44:40 INFO - Return code: 0 02:44:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:40 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:40 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:44:40 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:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:40 INFO - 'HOME': '/Users/cltbld', 02:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:40 INFO - 'LOGNAME': 'cltbld', 02:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:40 INFO - 'MOZ_NO_REMOTE': '1', 02:44:40 INFO - 'NO_EM_RESTART': '1', 02:44:40 INFO - 'PAGER': '/bin/cat', 02:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:40 INFO - 'PWD': '/builds/slave/test', 02:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:40 INFO - 'SHELL': '/bin/bash', 02:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:40 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:40 INFO - 'USER': 'cltbld', 02:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:40 INFO - 'XPC_FLAGS': '0x0', 02:44:40 INFO - 'XPC_SERVICE_NAME': '0', 02:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:40 INFO - Downloading/unpacking psutil>=3.1.1 02:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:43 INFO - Installing collected packages: psutil 02:44:43 INFO - Running setup.py install for psutil 02:44:43 INFO - building 'psutil._psutil_osx' extension 02:44:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:44:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:44:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:44:44 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:44:44 INFO - building 'psutil._psutil_posix' extension 02:44:44 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:44:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:44:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:44:44 INFO - ^ 02:44:44 INFO - 1 warning generated. 02:44:44 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:44:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:44 INFO - Successfully installed psutil 02:44:44 INFO - Cleaning up... 02:44:44 INFO - Return code: 0 02:44:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:44 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:44 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:44:44 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:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:44 INFO - 'HOME': '/Users/cltbld', 02:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:44 INFO - 'LOGNAME': 'cltbld', 02:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:44 INFO - 'MOZ_NO_REMOTE': '1', 02:44:44 INFO - 'NO_EM_RESTART': '1', 02:44:44 INFO - 'PAGER': '/bin/cat', 02:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:44 INFO - 'PWD': '/builds/slave/test', 02:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:44 INFO - 'SHELL': '/bin/bash', 02:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:44 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:44 INFO - 'USER': 'cltbld', 02:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:44 INFO - 'XPC_FLAGS': '0x0', 02:44:44 INFO - 'XPC_SERVICE_NAME': '0', 02:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:47 INFO - Installing collected packages: mozsystemmonitor 02:44:47 INFO - Running setup.py install for mozsystemmonitor 02:44:47 INFO - Successfully installed mozsystemmonitor 02:44:47 INFO - Cleaning up... 02:44:47 INFO - Return code: 0 02:44:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44:47 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:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:47 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:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:47 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:47 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:44:47 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:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:47 INFO - 'HOME': '/Users/cltbld', 02:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:47 INFO - 'LOGNAME': 'cltbld', 02:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:47 INFO - 'MOZ_NO_REMOTE': '1', 02:44:47 INFO - 'NO_EM_RESTART': '1', 02:44:47 INFO - 'PAGER': '/bin/cat', 02:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:47 INFO - 'PWD': '/builds/slave/test', 02:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:47 INFO - 'SHELL': '/bin/bash', 02:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:47 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:47 INFO - 'USER': 'cltbld', 02:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:47 INFO - 'XPC_FLAGS': '0x0', 02:44:47 INFO - 'XPC_SERVICE_NAME': '0', 02:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:47 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:50 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:50 INFO - warning: no files found matching '*.txt' 02:44:50 INFO - no previously-included directories found matching 'build' 02:44:50 INFO - no previously-included directories found matching 'dist' 02:44:50 INFO - no previously-included directories found matching '.git*' 02:44:50 INFO - Installing collected packages: jsonschema, functools32 02:44:50 INFO - Running setup.py install for functools32 02:44:50 INFO - warning: no files found matching '*.txt' 02:44:50 INFO - no previously-included directories found matching 'build' 02:44:50 INFO - no previously-included directories found matching 'dist' 02:44:50 INFO - no previously-included directories found matching '.git*' 02:44:50 INFO - Successfully installed jsonschema functools32 02:44:50 INFO - Cleaning up... 02:44:50 INFO - Return code: 0 02:44:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:50 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:50 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:44:50 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:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:50 INFO - 'HOME': '/Users/cltbld', 02:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:50 INFO - 'LOGNAME': 'cltbld', 02:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:50 INFO - 'MOZ_NO_REMOTE': '1', 02:44:50 INFO - 'NO_EM_RESTART': '1', 02:44:50 INFO - 'PAGER': '/bin/cat', 02:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:50 INFO - 'PWD': '/builds/slave/test', 02:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:50 INFO - 'SHELL': '/bin/bash', 02:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:50 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:50 INFO - 'USER': 'cltbld', 02:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:50 INFO - 'XPC_FLAGS': '0x0', 02:44:50 INFO - 'XPC_SERVICE_NAME': '0', 02:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:51 INFO - Cleaning up... 02:44:51 INFO - Return code: 0 02:44:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:51 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:51 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:44:51 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:44:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:51 INFO - 'HOME': '/Users/cltbld', 02:44:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:51 INFO - 'LOGNAME': 'cltbld', 02:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:51 INFO - 'MOZ_NO_REMOTE': '1', 02:44:51 INFO - 'NO_EM_RESTART': '1', 02:44:51 INFO - 'PAGER': '/bin/cat', 02:44:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:51 INFO - 'PWD': '/builds/slave/test', 02:44:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:51 INFO - 'SHELL': '/bin/bash', 02:44:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:51 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:51 INFO - 'USER': 'cltbld', 02:44:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:51 INFO - 'XPC_FLAGS': '0x0', 02:44:51 INFO - 'XPC_SERVICE_NAME': '0', 02:44:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:51 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:55 INFO - Downloading docopt-0.6.1.tar.gz 02:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:55 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:55 INFO - Running setup.py install for blobuploader 02:44:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:55 INFO - Running setup.py install for requests 02:44:55 INFO - Running setup.py install for docopt 02:44:55 INFO - Successfully installed blobuploader requests docopt 02:44:55 INFO - Cleaning up... 02:44:56 INFO - Return code: 0 02:44:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:44:56 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:56 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:44:56 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:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:44:56 INFO - 'HOME': '/Users/cltbld', 02:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:44:56 INFO - 'LOGNAME': 'cltbld', 02:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:56 INFO - 'MOZ_NO_REMOTE': '1', 02:44:56 INFO - 'NO_EM_RESTART': '1', 02:44:56 INFO - 'PAGER': '/bin/cat', 02:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:56 INFO - 'PWD': '/builds/slave/test', 02:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:44:56 INFO - 'SHELL': '/bin/bash', 02:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:44:56 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:44:56 INFO - 'USER': 'cltbld', 02:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:56 INFO - 'XPC_FLAGS': '0x0', 02:44:56 INFO - 'XPC_SERVICE_NAME': '0', 02:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-vHQdlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-AqDVwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-J3tkGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ir78gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ygHgGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-5VpnoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:56 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-MuPyIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-xhUAbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-BcO9u5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-0tsi49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Oppt9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-GM0v5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Dzn9ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-YgTOC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-JxdtI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-oZqqd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:57 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-tG837a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:58 INFO - Running setup.py install for manifestparser 02:44:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozcrash 02:44:58 INFO - Running setup.py install for mozdebug 02:44:58 INFO - Running setup.py install for mozdevice 02:44:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozfile 02:44:58 INFO - Running setup.py install for mozhttpd 02:44:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:58 INFO - Running setup.py install for mozinfo 02:44:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozInstall 02:44:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozleak 02:44:59 INFO - Running setup.py install for mozlog 02:44:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for moznetwork 02:44:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozprocess 02:44:59 INFO - Running setup.py install for mozprofile 02:44:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:59 INFO - Running setup.py install for mozrunner 02:45:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:45:00 INFO - Running setup.py install for mozscreenshot 02:45:00 INFO - Running setup.py install for moztest 02:45:00 INFO - Running setup.py install for mozversion 02:45:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:45:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:45:00 INFO - Cleaning up... 02:45:00 INFO - Return code: 0 02:45:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:00 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:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:01 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:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:01 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:01 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:45:01 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:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:01 INFO - 'HOME': '/Users/cltbld', 02:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:01 INFO - 'LOGNAME': 'cltbld', 02:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:01 INFO - 'MOZ_NO_REMOTE': '1', 02:45:01 INFO - 'NO_EM_RESTART': '1', 02:45:01 INFO - 'PAGER': '/bin/cat', 02:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:01 INFO - 'PWD': '/builds/slave/test', 02:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:01 INFO - 'SHELL': '/bin/bash', 02:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:45:01 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:01 INFO - 'USER': 'cltbld', 02:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:01 INFO - 'XPC_FLAGS': '0x0', 02:45:01 INFO - 'XPC_SERVICE_NAME': '0', 02:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-mahSJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Un3TPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-3KV64e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-TWbgFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-c7ptaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-PNZVd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:45:01 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:45:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:45:01 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ihkJSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-MdTTKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Psd5Qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-PWiHfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-UhhhYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-RnqKXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-LLUolf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-mas0wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-xieweL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:45:02 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:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:45:02 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-hLFq36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:45:03 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:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:45:03 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-g1igPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:45:03 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:45:03 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:45:03 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:45:03 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:45:03 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:45:03 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:45:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:45:03 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:45:03 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:45:03 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:45:03 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:45:03 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:45:03 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:45:05 INFO - Downloading blessings-1.6.tar.gz 02:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:45:06 INFO - Installing collected packages: blessings 02:45:06 INFO - Running setup.py install for blessings 02:45:06 INFO - Successfully installed blessings 02:45:06 INFO - Cleaning up... 02:45:06 INFO - Return code: 0 02:45:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:45:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:06 INFO - Reading from file tmpfile_stdout 02:45:06 INFO - Current package versions: 02:45:06 INFO - blessings == 1.6 02:45:06 INFO - blobuploader == 1.2.4 02:45:06 INFO - docopt == 0.6.1 02:45:06 INFO - functools32 == 3.2.3-2 02:45:06 INFO - jsonschema == 2.5.1 02:45:06 INFO - manifestparser == 1.1 02:45:06 INFO - mozInstall == 1.12 02:45:06 INFO - mozcrash == 0.17 02:45:06 INFO - mozdebug == 0.1 02:45:06 INFO - mozdevice == 0.48 02:45:06 INFO - mozfile == 1.2 02:45:06 INFO - mozhttpd == 0.7 02:45:06 INFO - mozinfo == 0.9 02:45:06 INFO - mozleak == 0.1 02:45:06 INFO - mozlog == 3.3 02:45:06 INFO - moznetwork == 0.27 02:45:06 INFO - mozprocess == 0.23 02:45:06 INFO - mozprofile == 0.28 02:45:06 INFO - mozrunner == 6.12 02:45:06 INFO - mozscreenshot == 0.1 02:45:06 INFO - mozsystemmonitor == 0.3 02:45:06 INFO - moztest == 0.7 02:45:06 INFO - mozversion == 1.4 02:45:06 INFO - psutil == 3.1.1 02:45:06 INFO - requests == 1.2.3 02:45:06 INFO - wsgiref == 0.1.2 02:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:06 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 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:06 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:45:06 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:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:06 INFO - 'HOME': '/Users/cltbld', 02:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:06 INFO - 'LOGNAME': 'cltbld', 02:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:06 INFO - 'MOZ_NO_REMOTE': '1', 02:45:06 INFO - 'NO_EM_RESTART': '1', 02:45:06 INFO - 'PAGER': '/bin/cat', 02:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:06 INFO - 'PWD': '/builds/slave/test', 02:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:06 INFO - 'SHELL': '/bin/bash', 02:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:45:06 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:06 INFO - 'USER': 'cltbld', 02:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:06 INFO - 'XPC_FLAGS': '0x0', 02:45:06 INFO - 'XPC_SERVICE_NAME': '0', 02:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 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:45:06 INFO - Cleaning up... 02:45:06 INFO - Return code: 0 02:45:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f94fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab3470210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, '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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:45:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:06 INFO - 'HOME': '/Users/cltbld', 02:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:06 INFO - 'LOGNAME': 'cltbld', 02:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:06 INFO - 'MOZ_NO_REMOTE': '1', 02:45:06 INFO - 'NO_EM_RESTART': '1', 02:45:06 INFO - 'PAGER': '/bin/cat', 02:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:06 INFO - 'PWD': '/builds/slave/test', 02:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:06 INFO - 'SHELL': '/bin/bash', 02:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:45:06 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:06 INFO - 'USER': 'cltbld', 02:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:06 INFO - 'XPC_FLAGS': '0x0', 02:45:06 INFO - 'XPC_SERVICE_NAME': '0', 02:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:45:07 INFO - Cleaning up... 02:45:07 INFO - Return code: 0 02:45:07 INFO - Running post-action listener: _resource_record_post_action 02:45:07 INFO - Running post-action listener: _start_resource_monitoring 02:45:07 INFO - Starting resource monitoring. 02:45:07 INFO - [mozharness: 2016-09-08 09:45:07.227935Z] Finished create-virtualenv step (success) 02:45:07 INFO - [mozharness: 2016-09-08 09:45:07.228973Z] Running install step. 02:45:07 INFO - Running pre-action listener: _resource_record_pre_action 02:45:07 INFO - Running main action method: install 02:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:45:07 INFO - Reading from file tmpfile_stdout 02:45:07 INFO - Detecting whether we're running mozinstall >=1.0... 02:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:45:07 INFO - Reading from file tmpfile_stdout 02:45:07 INFO - Output received: 02:45:07 INFO - Usage: mozinstall [options] installer 02:45:07 INFO - Options: 02:45:07 INFO - -h, --help show this help message and exit 02:45:07 INFO - -d DEST, --destination=DEST 02:45:07 INFO - Directory to install application into. [default: 02:45:07 INFO - "/builds/slave/test"] 02:45:07 INFO - --app=APP Application being installed. [default: firefox] 02:45:07 INFO - mkdir: /builds/slave/test/build/application 02:45:07 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:45:07 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:45:41 INFO - Reading from file tmpfile_stdout 02:45:41 INFO - Output received: 02:45:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:45:41 INFO - Running post-action listener: _resource_record_post_action 02:45:41 INFO - [mozharness: 2016-09-08 09:45:41.516570Z] Finished install step (success) 02:45:41 INFO - [mozharness: 2016-09-08 09:45:41.516735Z] Running run-tests step. 02:45:41 INFO - Running pre-action listener: _resource_record_pre_action 02:45:41 INFO - Running main action method: run_tests 02:45:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:45:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:45:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:45:41 INFO - 2016-09-08 02:45:41.530 screenresolution[1644:7417] starting screenresolution argv=screenresolution get 02:45:41 INFO - 2016-09-08 02:45:41.533 screenresolution[1644:7417] Display 0: 1600x1200x32@60 02:45:41 INFO - 2016-09-08 02:45:41.539 screenresolution[1645:7419] starting screenresolution argv=screenresolution list 02:45:41 INFO - Available Modes on Display 0 02:45:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:45:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:45:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:45:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:45:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:45:41 INFO - Intel Iris: 02:45:41 INFO - Chipset Model: Intel Iris 02:45:41 INFO - Type: GPU 02:45:41 INFO - Bus: Built-In 02:45:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:45:41 INFO - Vendor: Intel (0x8086) 02:45:41 INFO - Device ID: 0x0a2e 02:45:41 INFO - Revision ID: 0x0009 02:45:41 INFO - Displays: 02:45:41 INFO - Display: 02:45:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:45:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:45:41 INFO - Display Serial Number: 1600x1200 60 02:45:41 INFO - Main Display: Yes 02:45:41 INFO - Mirror: Off 02:45:41 INFO - Online: Yes 02:45:41 INFO - Rotation: Supported 02:45:41 INFO - Return code: 0 02:45:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:41 INFO - Python 2.7.3 02:45:41 INFO - Return code: 0 02:45:41 INFO - grabbing minidump binary from tooltool 02:45:41 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:45:41 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 0x10f8b3d30>, '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 0x10f618db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8abb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:41 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:45:41 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:45:41 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:45:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:41 INFO - Return code: 0 02:45:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:45:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:45:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:41 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/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--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:45:41 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/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0272 --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:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbGaHeZWmf/Render', 02:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:41 INFO - 'HOME': '/Users/cltbld', 02:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:41 INFO - 'LOGNAME': 'cltbld', 02:45:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:41 INFO - 'MOZ_NO_REMOTE': '1', 02:45:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:41 INFO - 'NO_EM_RESTART': '1', 02:45:41 INFO - 'PAGER': '/bin/cat', 02:45:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:41 INFO - 'PWD': '/builds/slave/test', 02:45:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:41 INFO - 'SHELL': '/bin/bash', 02:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZJzt3hdNEg/Listeners', 02:45:41 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 02:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:41 INFO - 'USER': 'cltbld', 02:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:41 INFO - 'XPC_FLAGS': '0x0', 02:45:41 INFO - 'XPC_SERVICE_NAME': '0', 02:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:41 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/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--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:45:42 INFO - mozversion application_buildid: 20160907175716 02:45:42 INFO - mozversion application_changeset: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 02:45:42 INFO - mozversion application_display_name: Nightly 02:45:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:42 INFO - mozversion application_name: Firefox 02:45:42 INFO - mozversion application_remotingname: firefox 02:45:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:42 INFO - mozversion application_vendor: Mozilla 02:45:42 INFO - mozversion application_version: 51.0a1 02:45:42 INFO - mozversion platform_buildid: 20160907175716 02:45:42 INFO - mozversion platform_changeset: 77940cbf0c2a9f52c209fbbde5b2e7d4c74a1501 02:45:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:42 INFO - mozversion platform_version: 51.0a1 02:45:42 INFO - using testdate: 1473327942 02:45:42 INFO - actual date: 1473327942 02:45:42 INFO - starting webserver on 'localhost:49273' 02:45:42 INFO - SUITE-START | Running 2 tests 02:45:42 INFO - TEST-START | dromaeo_css 02:45:42 INFO - operating with platform_type : mac_ 02:45:42 INFO - Initialising browser for dromaeo_css test... 02:45:42 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp0ODP6q/profile http://localhost:49273/getInfo.html) 02:45:45 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 02:45:45 INFO - PROCESS | 1655 | colorDepth:24 02:45:45 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 02:45:45 INFO - PROCESS | 1655 | __metrics 02:45:45 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 02:45:46 INFO - TEST-INFO | 1655: exit 0 02:45:46 INFO - Browser initialized. 02:45:46 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:46 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp0ODP6q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:58 INFO - PROCESS | 1661 | RSS: Main: 243806208 02:45:58 INFO - PROCESS | 1661 | 02:46:47 INFO - PROCESS | 1661 | 1473328007747 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:48:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 02:49:01 INFO - PROCESS | 1661 | RSS: Main: 264241152 02:49:01 INFO - PROCESS | 1661 | 02:52:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 02:52:03 INFO - PROCESS | 1661 | RSS: Main: 269713408 02:52:03 INFO - PROCESS | 1661 | 02:55:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 02:55:06 INFO - PROCESS | 1661 | RSS: Main: 274948096 02:55:06 INFO - PROCESS | 1661 | 02:58:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 02:58:09 INFO - PROCESS | 1661 | RSS: Main: 271159296 02:58:09 INFO - PROCESS | 1661 | 03:01:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 03:01:12 INFO - PROCESS | 1661 | RSS: Main: 276615168 03:01:12 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 03:04:15 INFO - PROCESS | 1661 | RSS: Main: 279314432 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | __start_tp_report 03:04:15 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 03:04:15 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 03:04:15 INFO - PROCESS | 1661 | |i|pagename|runs| 03:04:15 INFO - PROCESS | 1661 | |0;dojo.html;2830.4339132173573;3326.517654940034;3357.294968056708;3399.711024562912;3401.5407919930817;5635.802746903859;5658.066419040863;5665.8583535411635;5687.374388817227;5747;5724.971375143118;5747.310322761264;5793.739281732322;5805.303363596364;5818.476337129647;5134.537891589756;5210.505002024802;5210.895782084375;5270.789168433258;5296.682199068049;5288.947110528884;5313.442088580701;5331.706756128431;5350.759215835296;5359.222912677659;5165.66423182492;5171.198464238049;5249.632525723201;5259.973700131513;5319.468053194688;5151.6908985461;5162.328897243372;5170.250313704491;5189.792408303701;5275.314209152824;5124.820631277925;5151.0470562945975;5175.353080864892;5232.764525596357;5250.107481728115;4167.937480937789;5262.526372626444;5677.772889084432;5741.598088133832;5760.596758226926;4997.85006449807;5070.264811602297;5148.687084793353;5436.238926550286;6257.8748425031245;6200.193974783295;6255.186825712628;6263.592866463711;6278.37216278373;6289.37106289372;5792.478676919055;5860.150278209636;5869.706514674249;5887.558433117533;5928.67392293428;5762;5791;6301.432871041537;6305.432511074026;6311.810645680637;6172.956335074948;6188.566800323934;6207.317195108534;6222.719977601018;6233.065040243909;5059.645824792303;6153.507719382439;6154.953657878172;6225;6226.283977342573;6214.627122372672;6216.627002379872;6232.3144454110015;6240.843978900473;6248;5860.267466566679;6169.629822210682;6195.2255968004;6220.906686399708;6269.777393408242;4934.432540257916;5013.824516141917;5035.295058691786;5046.7476626168545;5074.49255074497;3754.5869954305003;3812.5806161322234;4426.889327766831;4490.3040028795585;4501.167284052461;5771.567132465082;5842.2405087338375;5853.268341457318;5865.882682346414;5868.237129173265;5334.839954801362;6045.304789949124;6154.969225153845;6158.568900177034;6262.937370626236;5843.678597677083;5847.970760146172;5859.296884373817;5879.971005074095;5891.410858914074;5466.945330546723;5469.179623056573;5501.202325662756;5501.532369748536;5502.614816962774;6166.907496387557;6255.218097737783;6270.9372906270355;6302.275238347649;6378.904316435257;6237.906431403442;6247.12540244357;6250.624962502083;6259.311475737756;6259.937400625936;6305.779297724557;6362.840928976812;6375.139356186855;6393.200849893763;6393.584417012879;6066.848328791815;6117.462039585284;6142.416470435302;6154.723037463415;6170;6138.693065346804;6203.503719702503;6256.687165641609;6257.78097766576;6285.2457705075685;6180.938190618216;6210.503159747119;6212.223472065992;6213.782517611862;6236.812895613139;6017.879642407215;6109.586862300737;6130.417610327012;6135.601185922901;6184.257889053162;5881.882362352814;5950.7917222896995;6016.7292471838;6048.455638992512;6051;6121.571489995657;6129.387061293835;6149.0776383542825;6170.352113028068;6229.0967809668255;5892.764289428546;6003.189569408249;6020.157177995172;6022.909656355246;6050.878982420415 03:04:15 INFO - PROCESS | 1661 | |1;ext.html;479.8056787001266;515.5926817813927;523.7459831981488;524.6537285391643;526.2448386565279;38306.61693383065;40886.38670419939;40908.590914090884;40930;40982.180356392855;41627.79186104073;42081.789591052;42115;42131.57868421315;42164;8490.047289123506;9751.463669498193;9928.056834600702;9956.054174853378;9967.003299670048;9791.020897910188;9885.209183265357;9904.455254960974;9909.801803963916;9911.653092141776;9877.160441362494;9881.703548893545;9904.108630223278;9921.454320012397;9924.851127233062;9844.655437059704;9906.009399060109;9910.653127140587;9926.305158638897;9948.950255248677;9824.557894894748;9890.456024918627;9892.109710126124;9932.602695892156;9938.552765125514;35605;39390.40914386287;39790.80104599487;39917.40123898144;39923.60076399257;3420.7605467617277;3726.8881933541766;3971.8808435746973;3977.9005524861823;3998.4402183694306;30524.389512209855;30844.845775771202;30861.537076943863;30876.228094297596;31285;3232.434323993292;3244.5782048333567;3263.4288999425244;3282.048206020251;3334.233126380946;495352;506482;506678;508483;508550.4572477151;57850;58129.418705813245;58181.709091455115;58248.999999999156;58263.29972053183;34103.488447673306;35240.295194096485;35308.29383412317;61426.692866535384;61450.692746535984;16020.599485012968;16146.596335091483;16223.756643650355;16238.837611623732;16248.187590620422;12313.93843030797;12387.380630968415;12472.937635311766;12482;12492.50029998808;15755.68488630221;18893.244270229312;18939.905300473685;19070.855748655125;19086.713699294523;7735.574543400055;7850.960745196237;7929.986605090029;7947.642356093989;7961.522308661499;867.8307729992591;974.7660561465198;1035.0942924940678;1049.1659130990881;1073.1897417449932;26306.473870522866;27204.59193112105;29763.40473190524;30289.394212115636;30910.53634195489;1002.9448380339152;1043.9977621483308;1051.085555566662;1071.890593235997;1110.1396417776127;15059.623509412133;15645.687086258438;15658.295376708076;15749.606259843596;16005.839941600669;660458;681802;682674;684430;694375;56425;56775;56902.43097568971;57023.42976570264;57076.42923570794;1316.8740726678743;1324.1790090144173;1351.0610125962362;1354.715509742965;1358.0832937766968;28734;29672.406551869273;29697.70302297036;30222;30464.086077417713;49955;50004.49995500144;50387.496125038284;50461.243081353074;50579.747101264256;578347;579112;579520;581297;582169;21635.242766503095;26322.210333690018;26354.4729105413;26767.464650707265;26779.464410712066;46619.766901165276;46944.7652761734;47707;47721;48018.03963920632;38875;39349.213015738955;39556.60443395529;40194.799026004686;40573.594264056985;31648.208794780316;38389.616103838605;38943.805280973414;39032.609673902894;40425.595744042184;31985.520217197227;33643.654253829576;34477.48283775695;38669.2266154681;39528.60471395249;29232.123036308945;29886.103416897527;31474;34832.9550113497;35480.645193548764;24033.399165020313;25657;25880.352991175372;26004.479910401307;26020.252836046067 03:04:15 INFO - PROCESS | 1661 | |2;jquery.html;314.79538300104934;365.5814092863671;366.27843148996476;381.1196136923708;402.79457476686883;19660.311889083885;19730.901345493272;20311.08600112995;20813.583728325444;21055.578888422224;19980.700289495668;20418.183272669074;20448.07983640736;20858.791412085913;20963;5917.14201440793;5919.733611987471;6082.847928801771;6168.414000669929;6190.102435146901;5985.162077309158;6074.422929821682;6076.058210977283;6084.787032453865;6085.969570152165;5780.306363236417;5804.680742559158;6010.038393856984;6071.241094863142;6083.847903802418;6054.939450605481;6093.232962440887;6117.204763380754;6117.90823137651;6118.082287656887;5029.823956161554;5147.639665223436;5152.690838549699;5223.738813059332;5234.031704134747;19941;25698.35754106162;25752.871235643703;25811.612825807628;26013;1861.4973957031661;2201.086549082129;2622.239550530344;2848.458792829356;3103.3482968576627;15356.692866142726;15617.297221625055;15640.608984775357;15973.680526389408;16225.188740562924;1820.7072978185504;1878.8778729382545;1900.6483800496953;1909.1313452379134;1913.3877159309027;76257.61871190609;106318;118913.40543297314;121006.39496802547;123827.9999999991;11442;11553.30680159193;11706.94146529279;11739.295642261492;11824.467898944567;14436.133831970115;14512.419503219964;14513.201773902536;14542.345594448276;14556.053856499293;8258.876116858251;8447.32421406286;8486.702965396182;8488.702895398632;8603.870941935877;8344.41589088758;8383.11977242393;8389.126480853412;8522.78693032679;8537.957310213409;3712.9443058353604;4458.910821783546;4560.890365119051;4574.794134263966;4597.310403439444;1636.7544868269854;1927.3254360973547;1969.891655958936;1970.832479239252;1974.634692581877;801.1587832775563;868.444195714743;892.2727976698969;941.5904081724472;1027.7841653252901;16349.836501635069;20397.592048158953;20620.587588248152;21145;21466.141354345964;706.33251577259;921.829461549602;921.861720741894;941.2140862379853;953.6376177052675;10511.894881051245;10914.038243986084;11042.33745975244;11042.392668403154;11048.723781905517;599831;601057;609151;610297;619211;121529.39235303766;126767;128014;128599.35700321439;132670;842.0947481457458;926.8702381666573;964.5611246882652;978.6085565773748;991.7850632974693;15650.765238521659;16347.937384069997;18828.435146945612;18835.340763073225;19206.61586768285;38383;42050.57949420466;53803;55404;56242.1563676537;81191.18808811836;81847;83341;83371;85045.57477212575;7825.804354891174;7915.564658858228;8109.107998120084;8251.2986396157;8299.585020749058;9145;10668.253222274367;12803.935980320039;12925.741485170056;13078.542251021168;10359.482025898826;10874.293170943864;10989.61536346224;11164.665060048212;11205.383703896365;10100.090991810772;10597.4701264938;10999.34003959765;11122.44387780624;11199.944000279946;10873.891261087605;11112.611058612909;11166.609168679057;11279.210455268381;11434.599789007345;7814.257645523666;7946.92053079485;8013.439059265796;8021.478603890729;8065.153158918229;8930.86603700932;9094.818103638023;11038.282511636718;11164.218504704593;11279.7744045117 03:04:15 INFO - PROCESS | 1661 | |3;mootools.html;1334.833145856768;1533.8542838430353;1702.5147832867635;1720.9139543022845;1877.2584828992556;6890.758823441174;8055.234752698484;8662.870056949136;9046.457212567235;9083.409578377408;8656.091110433408;8803.55982200889;8846.601902914368;8848.823023539526;8868.556572171381;2375.429896824758;2410.6986626671664;2411.03558576569;2417.359399759065;2426.4055306449854;2372.7389987101405;2409.0604664180983;2410.5660981023407;2429.259075981825;2432.1001229545004;2406.338256979327;2407.711074671033;2419.080749315258;2424.987875060622;2432.1852179519883;2379.381360846175;2387.77316154965;2397.61638137898;2398.2085911649115;2403.5793736096202;2355.729091154505;2371.5019845832394;2383.8688872112025;2385.8687772172525;2389.557931782608;7440.2931721486375;8846.955765221133;8916.866247006301;8917.955410222907;8960.641574337018;5270.683758974474;5440.891182176374;5463.153211252223;5475.616706830523;5491.752871120809;3422.024722953971;3550.378683730363;3687.0966613179676;3745.550533935945;3748.887533374003;1107.141964977141;1110.350444989682;1118.6196693124377;1129.9943500282527;1133.0482394788417;11583.36291503967;11605.6518304451;11654.941725291405;11705;11712.707182320424;4418.403515525427;4509.706869053501;4664.743439110814;4692.718436893729;4720.480747117883;9055.547222638974;9083.863742043875;9120.315976301805;9133.771655708528;9135.314851386172;5261;5261.789528418897;5268.552173065333;5276.551493123051;5285.418603953562;3988.8603898863403;4439.822407103679;4901;4910.680805747615;4929.975350123226;3375.835336808797;3415.55597772288;3554.306910152547;3744.2324323513612;3749.6250374962833;1171.3908767441053;1176.0709039858586;1183.201339096124;1190;1196.922200057011;760.3119177144692;863.5293764898146;899.2311573604604;909.0046399192873;938.8967213606497;5761.135829625506;5934.406559344031;5940.168376427304;6074.8270112131;6090.969545152335;883.5184824270788;923.3305853256336;928.8095940332215;933.2021121940778;937.9859302110474;3880.2239552089695;4014.397840323975;4029.5164580250557;4066.471358723347;4118.8352465901025;10366.948165259126;10753.301035432673;10782.730431739112;10940.56237750481;10978.725531861768;10868.61959831402;10877.292975956538;10887.673369798918;10901.672949811518;10926.617568385069;8119.269265766109;8306.210909963544;8313.210245026712;8326.043379805862;8344.8331033379;5101.285819985131;5561.499465048165;5839.532837373085;5912.438318359749;6066.423689749467;8609.48343099416;10151.898481015096;10201.642942497274;10329;10339.63811266602;9988.151007164472;10172.542235599563;10216.846747298649;10340.12108970746;10346.637867674595;7026.437884969088;7213.855722885618;7351.963240183765;7376.225496323023;7605.201453847488;8472;8531.402801804063;8541.615627296911;8546.743597692079;8560.186782255676;7517.398608111447;7588.734394296173;7616.162222155448;7706.961465192639;7750.224977502205;6170.753169873155;7312.305330993547;7434.665440055102;7532.945387645844;7559.924400755922;6921.861562768616;6934.514583979073;7016.7544135954995;7036.050133231948;7051.894221586783;4962.851114466572;4991.301217829579;5041.218611115283;5138.640295179451;5139.948600513947;4598.965232822588;4736.4316282046375;5050.72221027847;5180.870478238075;5254.816081437131 03:04:15 INFO - PROCESS | 1661 | |4;prototype.html;299.15339588963235;305.4059853584777;311.863258423048;313.7787859559011;315.2874503621815;4723.669343145981;5063.848084557465;5383.569314454844;5536.667799932007;5581.288443185277;4853.107288157613;5585.843998141047;5656.660600363981;5664.773409063643;5682.602217844753;4320.395144679747;4328.567143285678;4349.804258808361;4350.825966961318;4364.738115713068;3552.769070010454;3591.42537194049;4106.568810274923;4291.570842915715;4336.913261734763;3938.011304291343;4217.894552636178;4248.957510424887;4276.401303817468;4296.248156572603;3812.0850995761093;4292.1415716856445;4309.482862056558;4314.97842510787;4329.328954012133;3080.9845950770105;3669.6513831185994;3736.9626303736886;3740.195857890539;3761.930937984098;4825.841797968497;5076.543111120017;5199.454057323965;5522.3649280332875;5524.254225679522;4425.557444255581;4445.177642136216;4870.613399434287;4895.192293271606;4896.436909755388;5456.317960254968;5585.581081418869;5665.915011274834;5963.761449542013;5989.574222439919;3295.4624938797233;3937.960620393788;3972.344563147077;3984.382420724792;4030.9395359069636;5146.588272938156;5534.948359811623;6034.336223015464;6271.560990730651;6344.492440604787;3552.236269202134;4046.575109613448;4047.3119569673227;4054.0675644601906;4075.9592404076175;3831.8275677594575;3837.712171587142;3894.597944739948;4092.7339722917914;4109.876703698894;4892.779824907888;4900.436449808247;4911.312416261726;4916.950830491721;4931.679440836334;5238.292830467914;6019.860359956606;6113.358097399798;6131.14164017038;6163.414475624809;1353.35715535122;1392.1786146173806;1399.1185553101684;1405.6204824697274;1406.6413064668443;832.7834762961674;846.9328645906203;858.772425307294;862.9223369896739;867.4361664917727;4332.696711230246;4508.48152462471;4740.2178640523925;4788.281757736298;4821.011692603008;4629.837955671535;4754.215554433549;4756.405449318835;5236.947630523646;5241.790328386898;3815.828287727059;4174.246816337146;4523.982104026568;4580.938577640075;4592;2676.37105280259;2743.204470703474;2912.5922370868097;3188.6970737779875;3311.8840840570465;5692.629979051431;5774.913376299359;5946.70266486674;5970.761169553256;6117.785877494266;5289.867753306198;6039.48664363534;6408.686219325027;6409.903851442232;6427.22873255203;5062;5576.386597474275;6480.384363485461;6502.512311576746;6533.183352080895;5726.9713651431475;5925.555583331181;6198.574327904517;6286;6347.730453909329;5318.473724980758;6189.628622282497;6194.690265486617;6231.348692596375;6239.875202496016;5919.792807251725;6111.755529778759;6543.934560654333;6544.116544266653;6582.222799844111;3376.375370556455;3796.772193668389;4013.2776100302226;4038.353863381845;4052.615001574846;5175.663581867167;6182;6206.820704066213;6221.418111483684;6312.506559908902;5764.193012978102;6073.240844894388;6081.270247570143;6109.450149486567;6135.355787642234;4459.687821852439;5894.587378883437;5949.762009519571;5959.28488581373;5960.761569537343;4887.560119589254;5373.9731301343245;5457.263269458573;5874.765009399747;5912.526997840248;4813.638977076804;4912.70523768575;4932.802687892444;5990.281166260077;6058.5153187745755;5272.971770504868;5811.883762324815;5853.795117170878;5855;5869.484871792392 03:04:15 INFO - PROCESS | 1661 | |5;yui.html;640.4300172846165;700.1283402164306;710.9466789990752;723.5369363607291;776.631100227392;15485.458008969688;15629.921850390761;15866.68266634668;15904;15938.920305398457;11412;13892.81911037563;14228.786568201485;14339;14412.135271883695;669.3875104279579;827.7640872351366;835.3818174550819;847.631280393031;862.2584577263548;825.6490991328661;834.9248567628912;852.0925214646381;852.7740148860526;852.9616167272487;828.7596596986866;859.8710193470966;900.8648702694616;902.5036230073466;905.2079430498314;862.038826708219;888.3692578269437;897.7261935109789;899.2581120575519;899.8965119011332;660.2671035150978;664.6311297230036;665.4044630056121;667.4226793823357;669.8827705151627;14811.703765924729;14873.033252838532;14932.47736329234;14938.402463901431;15032;14505.927470362582;14557.70884582313;14559.199244041574;14580.781288280685;14654.706905861929;11494.712632184262;11511.827322590168;11603.709907252301;11706.297622142698;11753.35356555389;3168.493041113411;3858.305505009097;3881.7088718346236;3895.2209558088493;4022;107245.92754072437;110488.23779405499;111917.44041279823;112093.98825146856;112470.43764781205;11012.39431831241;11049.116070714324;11141.164412669083;11160.441977901073;11235.101191904629;22402.439939001328;22597.0961161555;22639.434014149778;22655.660165097535;22710.545789084455;7792.688292468351;8031.959840200761;8062.475939063942;8063.758087257392;8209.014918209854;4375.978120109443;4454.799534020977;4496.437945256843;4515.593596576324;4678.742669153161;3521.401361768454;3633.9636603634153;3636.363636363679;3637.1634524059614;3646.8176591170336;646.715445204118;665.4875745675803;665.7569986954824;674.4402146218724;679.9116114905031;598.8293336399105;609.920710307666;643.488426700774;662.453475882399;671.6943790575276;9037.322200834833;9464.763380915532;9490.478023708762;9600.519974001272;9661.468619225869;503.62983207342575;533.6264614769676;540.3111033432326;574.6867956963465;644.2172760096505;4466.508684044752;4591.563801438858;4654.999175177285;4685.835995740133;4732.515429399593;151210.24394878175;162131.37868621398;165042;167746.93518388935;171958;98143;126148;132702.67297327096;136765;136899;67471.3252867465;108213.8357232851;115228.42385788017;119850;128573;8391.860075680663;9826.5086745661;9933;10111.241656875616;10154.644587439405;55853.16220256781;55875;60448.39551604428;66217.66891165513;69514;83175.584122079;92840.53579732058;106235;106325;108729;10511.545982415879;12328.630141095782;12998.595259876953;13577.456901723821;14074.859251407765;15048.849511504744;15298.770518442456;15312.310946007234;15331.923340383226;15402.998805077634;13819.585412437644;13859.307034648025;14112.29438528049;14182.716345672823;14336.283185840875;13979.370928308048;14038.929805350908;14073.296335183404;14225.928870355581;14294.428222870969;11685.532578696757;12719.491220351083;14166.716665666836;14271.574822042843;14378.28108594587;9748.171405430618;11162.944185279022;11296.43517824122;11455.36995465224;11797.941010294893;9626.32615716893;10374.792504149724;10755;10897.182711296842;11882.16824822254 03:04:15 INFO - PROCESS | 1661 | __end_tp_report 03:04:15 INFO - PROCESS | 1661 | __start_cc_report 03:04:15 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,380 03:04:15 INFO - PROCESS | 1661 | __end_cc_report 03:04:15 INFO - PROCESS | 1661 | __startTimestamp1473329055450__endTimestamp 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | ------- Summary: start ------- 03:04:15 INFO - PROCESS | 1661 | Number of tests: 6 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5711.41 Median:5887.56 stddev:677.05 (11.5%) stddev-sans-first:640.29 03:04:15 INFO - PROCESS | 1661 | Values: 2830.4 3326.5 3357.3 3399.7 3401.5 5635.8 5658.1 5665.9 5687.4 5747.0 5725.0 5747.3 5793.7 5805.3 5818.5 5134.5 5210.5 5210.9 5270.8 5296.7 5288.9 5313.4 5331.7 5350.8 5359.2 5165.7 5171.2 5249.6 5260.0 5319.5 5151.7 5162.3 5170.3 5189.8 5275.3 5124.8 5151.0 5175.4 5232.8 5250.1 4167.9 5262.5 5677.8 5741.6 5760.6 4997.9 5070.3 5148.7 5436.2 6257.9 6200.2 6255.2 6263.6 6278.4 6289.4 5792.5 5860.2 5869.7 5887.6 5928.7 5762.0 5791.0 6301.4 6305.4 6311.8 6173.0 6188.6 6207.3 6222.7 6233.1 5059.6 6153.5 6155.0 6225.0 6226.3 6214.6 6216.6 6232.3 6240.8 6248.0 5860.3 6169.6 6195.2 6220.9 6269.8 4934.4 5013.8 5035.3 5046.7 5074.5 3754.6 3812.6 4426.9 4490.3 4501.2 5771.6 5842.2 5853.3 5865.9 5868.2 5334.8 6045.3 6155.0 6158.6 6262.9 5843.7 5848.0 5859.3 5880.0 5891.4 5466.9 5469.2 5501.2 5501.5 5502.6 6166.9 6255.2 6270.9 6302.3 6378.9 6237.9 6247.1 6250.6 6259.3 6259.9 6305.8 6362.8 6375.1 6393.2 6393.6 6066.8 6117.5 6142.4 6154.7 6170.0 6138.7 6203.5 6256.7 6257.8 6285.2 6180.9 6210.5 6212.2 6213.8 6236.8 6017.9 6109.6 6130.4 6135.6 6184.3 5881.9 5950.8 6016.7 6048.5 6051.0 6121.6 6129.4 6149.1 6170.4 6229.1 5892.8 6003.2 6020.2 6022.9 6050.9 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:71166.38 Median:39854.10 stddev:158758.58 (398.3%) stddev-sans-first:159115.23 03:04:15 INFO - PROCESS | 1661 | Values: 479.8 515.6 523.7 524.7 526.2 38306.6 40886.4 40908.6 40930.0 40982.2 41627.8 42081.8 42115.0 42131.6 42164.0 8490.0 9751.5 9928.1 9956.1 9967.0 9791.0 9885.2 9904.5 9909.8 9911.7 9877.2 9881.7 9904.1 9921.5 9924.9 9844.7 9906.0 9910.7 9926.3 9949.0 9824.6 9890.5 9892.1 9932.6 9938.6 35605.0 39390.4 39790.8 39917.4 39923.6 3420.8 3726.9 3971.9 3977.9 3998.4 30524.4 30844.8 30861.5 30876.2 31285.0 3232.4 3244.6 3263.4 3282.0 3334.2 495352.0 506482.0 506678.0 508483.0 508550.5 57850.0 58129.4 58181.7 58249.0 58263.3 34103.5 35240.3 35308.3 61426.7 61450.7 16020.6 16146.6 16223.8 16238.8 16248.2 12313.9 12387.4 12472.9 12482.0 12492.5 15755.7 18893.2 18939.9 19070.9 19086.7 7735.6 7851.0 7930.0 7947.6 7961.5 867.8 974.8 1035.1 1049.2 1073.2 26306.5 27204.6 29763.4 30289.4 30910.5 1002.9 1044.0 1051.1 1071.9 1110.1 15059.6 15645.7 15658.3 15749.6 16005.8 660458.0 681802.0 682674.0 684430.0 694375.0 56425.0 56775.0 56902.4 57023.4 57076.4 1316.9 1324.2 1351.1 1354.7 1358.1 28734.0 29672.4 29697.7 30222.0 30464.1 49955.0 50004.5 50387.5 50461.2 50579.7 578347.0 579112.0 579520.0 581297.0 582169.0 21635.2 26322.2 26354.5 26767.5 26779.5 46619.8 46944.8 47707.0 47721.0 48018.0 38875.0 39349.2 39556.6 40194.8 40573.6 31648.2 38389.6 38943.8 39032.6 40425.6 31985.5 33643.7 34477.5 38669.2 39528.6 29232.1 29886.1 31474.0 34833.0 35480.6 24033.4 25657.0 25880.4 26004.5 26020.3 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35108.59 Median:2975.90 stddev:101260.00 (3402.7%) stddev-sans-first:101510.36 03:04:15 INFO - PROCESS | 1661 | Values: 314.8 365.6 366.3 381.1 402.8 19660.3 19730.9 20311.1 20813.6 21055.6 19980.7 20418.2 20448.1 20858.8 20963.0 5917.1 5919.7 6082.8 6168.4 6190.1 5985.2 6074.4 6076.1 6084.8 6086.0 5780.3 5804.7 6010.0 6071.2 6083.8 6054.9 6093.2 6117.2 6117.9 6118.1 5029.8 5147.6 5152.7 5223.7 5234.0 19941.0 25698.4 25752.9 25811.6 26013.0 1861.5 2201.1 2622.2 2848.5 3103.3 15356.7 15617.3 15640.6 15973.7 16225.2 1820.7 1878.9 1900.6 1909.1 1913.4 76257.6 106318.0 118913.4 121006.4 123828.0 11442.0 11553.3 11706.9 11739.3 11824.5 14436.1 14512.4 14513.2 14542.3 14556.1 8258.9 8447.3 8486.7 8488.7 8603.9 8344.4 8383.1 8389.1 8522.8 8538.0 3712.9 4458.9 4560.9 4574.8 4597.3 1636.8 1927.3 1969.9 1970.8 1974.6 801.2 868.4 892.3 941.6 1027.8 16349.8 20397.6 20620.6 21145.0 21466.1 706.3 921.8 921.9 941.2 953.6 10511.9 10914.0 11042.3 11042.4 11048.7 599831.0 601057.0 609151.0 610297.0 619211.0 121529.4 126767.0 128014.0 128599.4 132670.0 842.1 926.9 964.6 978.6 991.8 15650.8 16347.9 18828.4 18835.3 19206.6 38383.0 42050.6 53803.0 55404.0 56242.2 81191.2 81847.0 83341.0 83371.0 85045.6 7825.8 7915.6 8109.1 8251.3 8299.6 9145.0 10668.3 12803.9 12925.7 13078.5 10359.5 10874.3 10989.6 11164.7 11205.4 10100.1 10597.5 10999.3 11122.4 11199.9 10873.9 11112.6 11166.6 11279.2 11434.6 7814.3 7946.9 8013.4 8021.5 8065.2 8930.9 9094.8 11038.3 11164.2 11279.8 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5690.32 Median:4977.08 stddev:3188.59 (64.1%) stddev-sans-first:3180.73 03:04:15 INFO - PROCESS | 1661 | Values: 1334.8 1533.9 1702.5 1720.9 1877.3 6890.8 8055.2 8662.9 9046.5 9083.4 8656.1 8803.6 8846.6 8848.8 8868.6 2375.4 2410.7 2411.0 2417.4 2426.4 2372.7 2409.1 2410.6 2429.3 2432.1 2406.3 2407.7 2419.1 2425.0 2432.2 2379.4 2387.8 2397.6 2398.2 2403.6 2355.7 2371.5 2383.9 2385.9 2389.6 7440.3 8847.0 8916.9 8918.0 8960.6 5270.7 5440.9 5463.2 5475.6 5491.8 3422.0 3550.4 3687.1 3745.6 3748.9 1107.1 1110.4 1118.6 1130.0 1133.0 11583.4 11605.7 11654.9 11705.0 11712.7 4418.4 4509.7 4664.7 4692.7 4720.5 9055.5 9083.9 9120.3 9133.8 9135.3 5261.0 5261.8 5268.6 5276.6 5285.4 3988.9 4439.8 4901.0 4910.7 4930.0 3375.8 3415.6 3554.3 3744.2 3749.6 1171.4 1176.1 1183.2 1190.0 1196.9 760.3 863.5 899.2 909.0 938.9 5761.1 5934.4 5940.2 6074.8 6091.0 883.5 923.3 928.8 933.2 938.0 3880.2 4014.4 4029.5 4066.5 4118.8 10366.9 10753.3 10782.7 10940.6 10978.7 10868.6 10877.3 10887.7 10901.7 10926.6 8119.3 8306.2 8313.2 8326.0 8344.8 5101.3 5561.5 5839.5 5912.4 6066.4 8609.5 10151.9 10201.6 10329.0 10339.6 9988.2 10172.5 10216.8 10340.1 10346.6 7026.4 7213.9 7352.0 7376.2 7605.2 8472.0 8531.4 8541.6 8546.7 8560.2 7517.4 7588.7 7616.2 7707.0 7750.2 6170.8 7312.3 7434.7 7532.9 7559.9 6921.9 6934.5 7016.8 7036.1 7051.9 4962.9 4991.3 5041.2 5138.6 5139.9 4599.0 4736.4 5050.7 5180.9 5254.8 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4701.91 Median:4997.40 stddev:1492.96 (29.9%) stddev-sans-first:1460.11 03:04:15 INFO - PROCESS | 1661 | Values: 299.2 305.4 311.9 313.8 315.3 4723.7 5063.8 5383.6 5536.7 5581.3 4853.1 5585.8 5656.7 5664.8 5682.6 4320.4 4328.6 4349.8 4350.8 4364.7 3552.8 3591.4 4106.6 4291.6 4336.9 3938.0 4217.9 4249.0 4276.4 4296.2 3812.1 4292.1 4309.5 4315.0 4329.3 3081.0 3669.7 3737.0 3740.2 3761.9 4825.8 5076.5 5199.5 5522.4 5524.3 4425.6 4445.2 4870.6 4895.2 4896.4 5456.3 5585.6 5665.9 5963.8 5989.6 3295.5 3938.0 3972.3 3984.4 4030.9 5146.6 5534.9 6034.3 6271.6 6344.5 3552.2 4046.6 4047.3 4054.1 4076.0 3831.8 3837.7 3894.6 4092.7 4109.9 4892.8 4900.4 4911.3 4917.0 4931.7 5238.3 6019.9 6113.4 6131.1 6163.4 1353.4 1392.2 1399.1 1405.6 1406.6 832.8 846.9 858.8 862.9 867.4 4332.7 4508.5 4740.2 4788.3 4821.0 4629.8 4754.2 4756.4 5236.9 5241.8 3815.8 4174.2 4524.0 4580.9 4592.0 2676.4 2743.2 2912.6 3188.7 3311.9 5692.6 5774.9 5946.7 5970.8 6117.8 5289.9 6039.5 6408.7 6409.9 6427.2 5062.0 5576.4 6480.4 6502.5 6533.2 5727.0 5925.6 6198.6 6286.0 6347.7 5318.5 6189.6 6194.7 6231.3 6239.9 5919.8 6111.8 6543.9 6544.1 6582.2 3376.4 3796.8 4013.3 4038.4 4052.6 5175.7 6182.0 6206.8 6221.4 6312.5 5764.2 6073.2 6081.3 6109.5 6135.4 4459.7 5894.6 5949.8 5959.3 5960.8 4887.6 5374.0 5457.3 5874.8 5912.5 4813.6 4912.7 4932.8 5990.3 6058.5 5273.0 5811.9 5853.8 5855.0 5869.5 03:04:15 INFO - PROCESS | 1661 | 03:04:15 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25430.29 Median:12939.52 stddev:41286.99 (319.1%) stddev-sans-first:41360.85 03:04:15 INFO - PROCESS | 1661 | Values: 640.4 700.1 710.9 723.5 776.6 15485.5 15629.9 15866.7 15904.0 15938.9 11412.0 13892.8 14228.8 14339.0 14412.1 669.4 827.8 835.4 847.6 862.3 825.6 834.9 852.1 852.8 853.0 828.8 859.9 900.9 902.5 905.2 862.0 888.4 897.7 899.3 899.9 660.3 664.6 665.4 667.4 669.9 14811.7 14873.0 14932.5 14938.4 15032.0 14505.9 14557.7 14559.2 14580.8 14654.7 11494.7 11511.8 11603.7 11706.3 11753.4 3168.5 3858.3 3881.7 3895.2 4022.0 107245.9 110488.2 111917.4 112094.0 112470.4 11012.4 11049.1 11141.2 11160.4 11235.1 22402.4 22597.1 22639.4 22655.7 22710.5 7792.7 8032.0 8062.5 8063.8 8209.0 4376.0 4454.8 4496.4 4515.6 4678.7 3521.4 3634.0 3636.4 3637.2 3646.8 646.7 665.5 665.8 674.4 679.9 598.8 609.9 643.5 662.5 671.7 9037.3 9464.8 9490.5 9600.5 9661.5 503.6 533.6 540.3 574.7 644.2 4466.5 4591.6 4655.0 4685.8 4732.5 151210.2 162131.4 165042.0 167746.9 171958.0 98143.0 126148.0 132702.7 136765.0 136899.0 67471.3 108213.8 115228.4 119850.0 128573.0 8391.9 9826.5 9933.0 10111.2 10154.6 55853.2 55875.0 60448.4 66217.7 69514.0 83175.6 92840.5 106235.0 106325.0 108729.0 10511.5 12328.6 12998.6 13577.5 14074.9 15048.8 15298.8 15312.3 15331.9 15403.0 13819.6 13859.3 14112.3 14182.7 14336.3 13979.4 14038.9 14073.3 14225.9 14294.4 11685.5 12719.5 14166.7 14271.6 14378.3 9748.2 11162.9 11296.4 11455.4 11797.9 9626.3 10374.8 10755.0 10897.2 11882.2 03:04:15 INFO - PROCESS | 1661 | -------- Summary: end -------- 03:04:15 INFO - PROCESS | 1661 | 03:04:16 INFO - TEST-INFO | 1661: exit 0 03:04:16 INFO - TEST-OK | dromaeo_css | took 1113977ms 03:04:16 INFO - TEST-START | kraken 03:04:16 INFO - operating with platform_type : mac_ 03:04:16 INFO - Initialising browser for kraken test... 03:04:16 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp2N3NoI/profile http://localhost:49273/getInfo.html) 03:04:18 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 03:04:18 INFO - PROCESS | 1671 | colorDepth:24 03:04:18 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 03:04:18 INFO - PROCESS | 1671 | __metrics 03:04:18 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 03:04:19 INFO - TEST-INFO | 1671: exit 0 03:04:19 INFO - Browser initialized. 03:04:19 INFO - Running cycle 1/1 for kraken test... 03:04:19 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp2N3NoI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:31 INFO - PROCESS | 1673 | RSS: Main: 243642368 03:04:31 INFO - PROCESS | 1673 | 03:04:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:35 INFO - PROCESS | 1673 | RSS: Main: 256757760 03:04:35 INFO - PROCESS | 1673 | 03:04:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 03:04:38 INFO - PROCESS | 1673 | RSS: Main: 265375744 03:04:38 INFO - PROCESS | 1673 | 03:04:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 03:04:42 INFO - PROCESS | 1673 | RSS: Main: 261742592 03:04:42 INFO - PROCESS | 1673 | 03:04:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 03:04:44 INFO - PROCESS | 1673 | RSS: Main: 264167424 03:04:44 INFO - PROCESS | 1673 | 03:04:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:47 INFO - PROCESS | 1673 | RSS: Main: 264388608 03:04:47 INFO - PROCESS | 1673 | 03:04:50 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:51 INFO - PROCESS | 1673 | RSS: Main: 265187328 03:04:51 INFO - PROCESS | 1673 | 03:04:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:54 INFO - PROCESS | 1673 | RSS: Main: 265396224 03:04:54 INFO - PROCESS | 1673 | 03:04:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 03:04:58 INFO - PROCESS | 1673 | RSS: Main: 265748480 03:04:58 INFO - PROCESS | 1673 | 03:05:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:05:00 INFO - PROCESS | 1673 | RSS: Main: 265093120 03:05:00 INFO - PROCESS | 1673 | 03:05:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:05:03 INFO - PROCESS | 1673 | RSS: Main: 265084928 03:05:03 INFO - PROCESS | 1673 | 03:05:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:05:06 INFO - PROCESS | 1673 | RSS: Main: 265441280 03:05:06 INFO - PROCESS | 1673 | 03:05:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:05:09 INFO - PROCESS | 1673 | RSS: Main: 265740288 03:05:09 INFO - PROCESS | 1673 | 03:05:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:05:13 INFO - PROCESS | 1673 | RSS: Main: 265695232 03:05:13 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 03:05:15 INFO - PROCESS | 1673 | RSS: Main: 267456512 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | __start_tp_report 03:05:15 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 03:05:15 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 03:05:15 INFO - PROCESS | 1673 | |i|pagename|runs| 03:05:15 INFO - PROCESS | 1673 | |0;ai-astar;106;114;115;127;120;116;117;115;116;132 03:05:15 INFO - PROCESS | 1673 | |1;audio-beat-detection;143;145;133;160;110;132;134;114;166;113 03:05:15 INFO - PROCESS | 1673 | |2;audio-dft;195;196;204;234;194;194;197;193;196;217 03:05:15 INFO - PROCESS | 1673 | |3;audio-fft;72;83;78;149;59;59;63;75;158;51 03:05:15 INFO - PROCESS | 1673 | |4;audio-oscillator;69;72;120;73;71;84;111;94;104;145 03:05:15 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;92;86;79;112;137;87;89;86;93;86 03:05:15 INFO - PROCESS | 1673 | |6;imaging-darkroom;137;96;96;119;97;153;154;97;97;102 03:05:15 INFO - PROCESS | 1673 | |7;imaging-desaturate;92;93;121;145;88;94;81;131;124;153 03:05:15 INFO - PROCESS | 1673 | |8;json-parse-financial;55;58;59;57;55;49;87;52;51;61 03:05:15 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;69;68;74;90;65;94;104;104;90 03:05:15 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;94;86;90;85;90;88;89;87;98;84 03:05:15 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;148;152;140;141;142;141;176;137;190;140 03:05:15 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;169;153;211;245;197;191;243;210;172;173 03:05:15 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;76;91;89;96;71;68;70;67;69;70 03:05:15 INFO - PROCESS | 1673 | __end_tp_report 03:05:15 INFO - PROCESS | 1673 | __start_cc_report 03:05:15 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,942 03:05:15 INFO - PROCESS | 1673 | __end_cc_report 03:05:15 INFO - PROCESS | 1673 | __startTimestamp1473329115647__endTimestamp 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | ------- Summary: start ------- 03:05:15 INFO - PROCESS | 1673 | Number of tests: 14 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:117.80 Median:116.50 stddev:7.21 (6.2%) stddev-sans-first:6.25 03:05:15 INFO - PROCESS | 1673 | Values: 106.0 114.0 115.0 127.0 120.0 116.0 117.0 115.0 116.0 132.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:138.50 stddev:19.19 (13.9%) stddev-sans-first:20.13 03:05:15 INFO - PROCESS | 1673 | Values: 143.0 145.0 133.0 160.0 110.0 132.0 134.0 114.0 166.0 113.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:202.00 Median:196.50 stddev:13.37 (6.8%) stddev-sans-first:13.94 03:05:15 INFO - PROCESS | 1673 | Values: 195.0 196.0 204.0 234.0 194.0 194.0 197.0 193.0 196.0 217.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:84.70 Median:67.50 stddev:37.62 (55.7%) stddev-sans-first:39.62 03:05:15 INFO - PROCESS | 1673 | Values: 72.0 83.0 78.0 149.0 59.0 59.0 63.0 75.0 158.0 51.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:71.50 stddev:25.48 (35.6%) stddev-sans-first:25.33 03:05:15 INFO - PROCESS | 1673 | Values: 69.0 72.0 120.0 73.0 71.0 84.0 111.0 94.0 104.0 145.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:86.50 stddev:17.20 (19.9%) stddev-sans-first:18.22 03:05:15 INFO - PROCESS | 1673 | Values: 92.0 86.0 79.0 112.0 137.0 87.0 89.0 86.0 93.0 86.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:114.80 Median:96.00 stddev:24.33 (25.3%) stddev-sans-first:24.44 03:05:15 INFO - PROCESS | 1673 | Values: 137.0 96.0 96.0 119.0 97.0 153.0 154.0 97.0 97.0 102.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:112.20 Median:84.50 stddev:25.77 (30.5%) stddev-sans-first:26.28 03:05:15 INFO - PROCESS | 1673 | Values: 92.0 93.0 121.0 145.0 88.0 94.0 81.0 131.0 124.0 153.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:57.50 stddev:10.72 (18.6%) stddev-sans-first:11.30 03:05:15 INFO - PROCESS | 1673 | Values: 55.0 58.0 59.0 57.0 55.0 49.0 87.0 52.0 51.0 61.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:82.30 Median:71.50 stddev:15.80 (22.1%) stddev-sans-first:15.47 03:05:15 INFO - PROCESS | 1673 | Values: 65.0 69.0 68.0 74.0 90.0 65.0 94.0 104.0 104.0 90.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:89.50 stddev:4.25 (4.8%) stddev-sans-first:4.13 03:05:15 INFO - PROCESS | 1673 | Values: 94.0 86.0 90.0 85.0 90.0 88.0 89.0 87.0 98.0 84.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:145.00 stddev:17.87 (12.3%) stddev-sans-first:18.93 03:05:15 INFO - PROCESS | 1673 | Values: 148.0 152.0 140.0 141.0 142.0 141.0 176.0 137.0 190.0 140.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.40 Median:203.50 stddev:31.16 (15.3%) stddev-sans-first:31.43 03:05:15 INFO - PROCESS | 1673 | Values: 169.0 153.0 211.0 245.0 197.0 191.0 243.0 210.0 172.0 173.0 03:05:15 INFO - PROCESS | 1673 | 03:05:15 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:73.50 stddev:10.95 (14.9%) stddev-sans-first:11.62 03:05:15 INFO - PROCESS | 1673 | Values: 76.0 91.0 89.0 96.0 71.0 68.0 70.0 67.0 69.0 70.0 03:05:15 INFO - PROCESS | 1673 | -------- Summary: end -------- 03:05:15 INFO - PROCESS | 1673 | 03:05:16 INFO - TEST-INFO | 1673: exit 0 03:05:16 INFO - TEST-OK | kraken | took 60438ms 03:05:16 INFO - SUITE-END | took 1174s 03:05:16 INFO - Completed test suite (00:19:34) 03:05:16 INFO - javascript benchmark 03:05:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7393.577238344161, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2830.4339132173573, 3326.517654940034, 3357.294968056708, 3399.711024562912, 3401.5407919930817, 5635.802746903859, 5658.066419040863, 5665.8583535411635, 5687.374388817227, 5747.0, 5724.971375143118, 5747.310322761264, 5793.739281732322, 5805.303363596364, 5818.476337129647, 5134.537891589756, 5210.505002024802, 5210.895782084375, 5270.789168433258, 5296.682199068049, 5288.947110528884, 5313.442088580701, 5331.706756128431, 5350.759215835296, 5359.222912677659, 5165.66423182492, 5171.198464238049, 5249.632525723201, 5259.973700131513, 5319.468053194688, 5151.6908985461, 5162.328897243372, 5170.250313704491, 5189.792408303701, 5275.314209152824, 5124.820631277925, 5151.0470562945975, 5175.353080864892, 5232.764525596357, 5250.107481728115, 4167.937480937789, 5262.526372626444, 5677.772889084432, 5741.598088133832, 5760.596758226926, 4997.85006449807, 5070.264811602297, 5148.687084793353, 5436.238926550286, 6257.8748425031245, 6200.193974783295, 6255.186825712628, 6263.592866463711, 6278.37216278373, 6289.37106289372, 5792.478676919055, 5860.150278209636, 5869.706514674249, 5887.558433117533, 5928.67392293428, 5762.0, 5791.0, 6301.432871041537, 6305.432511074026, 6311.810645680637, 6172.956335074948, 6188.566800323934, 6207.317195108534, 6222.719977601018, 6233.065040243909, 5059.645824792303, 6153.507719382439, 6154.953657878172, 6225.0, 6226.283977342573, 6214.627122372672, 6216.627002379872, 6232.3144454110015, 6240.843978900473, 6248.0, 5860.267466566679, 6169.629822210682, 6195.2255968004, 6220.906686399708, 6269.777393408242, 4934.432540257916, 5013.824516141917, 5035.295058691786, 5046.7476626168545, 5074.49255074497, 3754.5869954305003, 3812.5806161322234, 4426.889327766831, 4490.3040028795585, 4501.167284052461, 5771.567132465082, 5842.2405087338375, 5853.268341457318, 5865.882682346414, 5868.237129173265, 5334.839954801362, 6045.304789949124, 6154.969225153845, 6158.568900177034, 6262.937370626236, 5843.678597677083, 5847.970760146172, 5859.296884373817, 5879.971005074095, 5891.410858914074, 5466.945330546723, 5469.179623056573, 5501.202325662756, 5501.532369748536, 5502.614816962774, 6166.907496387557, 6255.218097737783, 6270.9372906270355, 6302.275238347649, 6378.904316435257, 6237.906431403442, 6247.12540244357, 6250.624962502083, 6259.311475737756, 6259.937400625936, 6305.779297724557, 6362.840928976812, 6375.139356186855, 6393.200849893763, 6393.584417012879, 6066.848328791815, 6117.462039585284, 6142.416470435302, 6154.723037463415, 6170.0, 6138.693065346804, 6203.503719702503, 6256.687165641609, 6257.78097766576, 6285.2457705075685, 6180.938190618216, 6210.503159747119, 6212.223472065992, 6213.782517611862, 6236.812895613139, 6017.879642407215, 6109.586862300737, 6130.417610327012, 6135.601185922901, 6184.257889053162, 5881.882362352814, 5950.7917222896995, 6016.7292471838, 6048.455638992512, 6051.0, 6121.571489995657, 6129.387061293835, 6149.0776383542825, 6170.352113028068, 6229.0967809668255, 5892.764289428546, 6003.189569408249, 6020.157177995172, 6022.909656355246, 6050.878982420415], "value": 5666.906714385872, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.8056787001266, 515.5926817813927, 523.7459831981488, 524.6537285391643, 526.2448386565279, 38306.61693383065, 40886.38670419939, 40908.590914090884, 40930.0, 40982.180356392855, 41627.79186104073, 42081.789591052, 42115.0, 42131.57868421315, 42164.0, 8490.047289123506, 9751.463669498193, 9928.056834600702, 9956.054174853378, 9967.003299670048, 9791.020897910188, 9885.209183265357, 9904.455254960974, 9909.801803963916, 9911.653092141776, 9877.160441362494, 9881.703548893545, 9904.108630223278, 9921.454320012397, 9924.851127233062, 9844.655437059704, 9906.009399060109, 9910.653127140587, 9926.305158638897, 9948.950255248677, 9824.557894894748, 9890.456024918627, 9892.109710126124, 9932.602695892156, 9938.552765125514, 35605.0, 39390.40914386287, 39790.80104599487, 39917.40123898144, 39923.60076399257, 3420.7605467617277, 3726.8881933541766, 3971.8808435746973, 3977.9005524861823, 3998.4402183694306, 30524.389512209855, 30844.845775771202, 30861.537076943863, 30876.228094297596, 31285.0, 3232.434323993292, 3244.5782048333567, 3263.4288999425244, 3282.048206020251, 3334.233126380946, 495352.0, 506482.0, 506678.0, 508483.0, 508550.4572477151, 57850.0, 58129.418705813245, 58181.709091455115, 58248.999999999156, 58263.29972053183, 34103.488447673306, 35240.295194096485, 35308.29383412317, 61426.692866535384, 61450.692746535984, 16020.599485012968, 16146.596335091483, 16223.756643650355, 16238.837611623732, 16248.187590620422, 12313.93843030797, 12387.380630968415, 12472.937635311766, 12482.0, 12492.50029998808, 15755.68488630221, 18893.244270229312, 18939.905300473685, 19070.855748655125, 19086.713699294523, 7735.574543400055, 7850.960745196237, 7929.986605090029, 7947.642356093989, 7961.522308661499, 867.8307729992591, 974.7660561465198, 1035.0942924940678, 1049.1659130990881, 1073.1897417449932, 26306.473870522866, 27204.59193112105, 29763.40473190524, 30289.394212115636, 30910.53634195489, 1002.9448380339152, 1043.9977621483308, 1051.085555566662, 1071.890593235997, 1110.1396417776127, 15059.623509412133, 15645.687086258438, 15658.295376708076, 15749.606259843596, 16005.839941600669, 660458.0, 681802.0, 682674.0, 684430.0, 694375.0, 56425.0, 56775.0, 56902.43097568971, 57023.42976570264, 57076.42923570794, 1316.8740726678743, 1324.1790090144173, 1351.0610125962362, 1354.715509742965, 1358.0832937766968, 28734.0, 29672.406551869273, 29697.70302297036, 30222.0, 30464.086077417713, 49955.0, 50004.49995500144, 50387.496125038284, 50461.243081353074, 50579.747101264256, 578347.0, 579112.0, 579520.0, 581297.0, 582169.0, 21635.242766503095, 26322.210333690018, 26354.4729105413, 26767.464650707265, 26779.464410712066, 46619.766901165276, 46944.7652761734, 47707.0, 47721.0, 48018.03963920632, 38875.0, 39349.213015738955, 39556.60443395529, 40194.799026004686, 40573.594264056985, 31648.208794780316, 38389.616103838605, 38943.805280973414, 39032.609673902894, 40425.595744042184, 31985.520217197227, 33643.654253829576, 34477.48283775695, 38669.2266154681, 39528.60471395249, 29232.123036308945, 29886.103416897527, 31474.0, 34832.9550113497, 35480.645193548764, 24033.399165020313, 25657.0, 25880.352991175372, 26004.479910401307, 26020.252836046067], "value": 19865.151394662946, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.79538300104934, 365.5814092863671, 366.27843148996476, 381.1196136923708, 402.79457476686883, 19660.311889083885, 19730.901345493272, 20311.08600112995, 20813.583728325444, 21055.578888422224, 19980.700289495668, 20418.183272669074, 20448.07983640736, 20858.791412085913, 20963.0, 5917.14201440793, 5919.733611987471, 6082.847928801771, 6168.414000669929, 6190.102435146901, 5985.162077309158, 6074.422929821682, 6076.058210977283, 6084.787032453865, 6085.969570152165, 5780.306363236417, 5804.680742559158, 6010.038393856984, 6071.241094863142, 6083.847903802418, 6054.939450605481, 6093.232962440887, 6117.204763380754, 6117.90823137651, 6118.082287656887, 5029.823956161554, 5147.639665223436, 5152.690838549699, 5223.738813059332, 5234.031704134747, 19941.0, 25698.35754106162, 25752.871235643703, 25811.612825807628, 26013.0, 1861.4973957031661, 2201.086549082129, 2622.239550530344, 2848.458792829356, 3103.3482968576627, 15356.692866142726, 15617.297221625055, 15640.608984775357, 15973.680526389408, 16225.188740562924, 1820.7072978185504, 1878.8778729382545, 1900.6483800496953, 1909.1313452379134, 1913.3877159309027, 76257.61871190609, 106318.0, 118913.40543297314, 121006.39496802547, 123827.9999999991, 11442.0, 11553.30680159193, 11706.94146529279, 11739.295642261492, 11824.467898944567, 14436.133831970115, 14512.419503219964, 14513.201773902536, 14542.345594448276, 14556.053856499293, 8258.876116858251, 8447.32421406286, 8486.702965396182, 8488.702895398632, 8603.870941935877, 8344.41589088758, 8383.11977242393, 8389.126480853412, 8522.78693032679, 8537.957310213409, 3712.9443058353604, 4458.910821783546, 4560.890365119051, 4574.794134263966, 4597.310403439444, 1636.7544868269854, 1927.3254360973547, 1969.891655958936, 1970.832479239252, 1974.634692581877, 801.1587832775563, 868.444195714743, 892.2727976698969, 941.5904081724472, 1027.7841653252901, 16349.836501635069, 20397.592048158953, 20620.587588248152, 21145.0, 21466.141354345964, 706.33251577259, 921.829461549602, 921.861720741894, 941.2140862379853, 953.6376177052675, 10511.894881051245, 10914.038243986084, 11042.33745975244, 11042.392668403154, 11048.723781905517, 599831.0, 601057.0, 609151.0, 610297.0, 619211.0, 121529.39235303766, 126767.0, 128014.0, 128599.35700321439, 132670.0, 842.0947481457458, 926.8702381666573, 964.5611246882652, 978.6085565773748, 991.7850632974693, 15650.765238521659, 16347.937384069997, 18828.435146945612, 18835.340763073225, 19206.61586768285, 38383.0, 42050.57949420466, 53803.0, 55404.0, 56242.1563676537, 81191.18808811836, 81847.0, 83341.0, 83371.0, 85045.57477212575, 7825.804354891174, 7915.564658858228, 8109.107998120084, 8251.2986396157, 8299.585020749058, 9145.0, 10668.253222274367, 12803.935980320039, 12925.741485170056, 13078.542251021168, 10359.482025898826, 10874.293170943864, 10989.61536346224, 11164.665060048212, 11205.383703896365, 10100.090991810772, 10597.4701264938, 10999.34003959765, 11122.44387780624, 11199.944000279946, 10873.891261087605, 11112.611058612909, 11166.609168679057, 11279.210455268381, 11434.599789007345, 7814.257645523666, 7946.92053079485, 8013.439059265796, 8021.478603890729, 8065.153158918229, 8930.86603700932, 9094.818103638023, 11038.282511636718, 11164.218504704593, 11279.7744045117], "value": 9567.93187233589, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.833145856768, 1533.8542838430353, 1702.5147832867635, 1720.9139543022845, 1877.2584828992556, 6890.758823441174, 8055.234752698484, 8662.870056949136, 9046.457212567235, 9083.409578377408, 8656.091110433408, 8803.55982200889, 8846.601902914368, 8848.823023539526, 8868.556572171381, 2375.429896824758, 2410.6986626671664, 2411.03558576569, 2417.359399759065, 2426.4055306449854, 2372.7389987101405, 2409.0604664180983, 2410.5660981023407, 2429.259075981825, 2432.1001229545004, 2406.338256979327, 2407.711074671033, 2419.080749315258, 2424.987875060622, 2432.1852179519883, 2379.381360846175, 2387.77316154965, 2397.61638137898, 2398.2085911649115, 2403.5793736096202, 2355.729091154505, 2371.5019845832394, 2383.8688872112025, 2385.8687772172525, 2389.557931782608, 7440.2931721486375, 8846.955765221133, 8916.866247006301, 8917.955410222907, 8960.641574337018, 5270.683758974474, 5440.891182176374, 5463.153211252223, 5475.616706830523, 5491.752871120809, 3422.024722953971, 3550.378683730363, 3687.0966613179676, 3745.550533935945, 3748.887533374003, 1107.141964977141, 1110.350444989682, 1118.6196693124377, 1129.9943500282527, 1133.0482394788417, 11583.36291503967, 11605.6518304451, 11654.941725291405, 11705.0, 11712.707182320424, 4418.403515525427, 4509.706869053501, 4664.743439110814, 4692.718436893729, 4720.480747117883, 9055.547222638974, 9083.863742043875, 9120.315976301805, 9133.771655708528, 9135.314851386172, 5261.0, 5261.789528418897, 5268.552173065333, 5276.551493123051, 5285.418603953562, 3988.8603898863403, 4439.822407103679, 4901.0, 4910.680805747615, 4929.975350123226, 3375.835336808797, 3415.55597772288, 3554.306910152547, 3744.2324323513612, 3749.6250374962833, 1171.3908767441053, 1176.0709039858586, 1183.201339096124, 1190.0, 1196.922200057011, 760.3119177144692, 863.5293764898146, 899.2311573604604, 909.0046399192873, 938.8967213606497, 5761.135829625506, 5934.406559344031, 5940.168376427304, 6074.8270112131, 6090.969545152335, 883.5184824270788, 923.3305853256336, 928.8095940332215, 933.2021121940778, 937.9859302110474, 3880.2239552089695, 4014.397840323975, 4029.5164580250557, 4066.471358723347, 4118.8352465901025, 10366.948165259126, 10753.301035432673, 10782.730431739112, 10940.56237750481, 10978.725531861768, 10868.61959831402, 10877.292975956538, 10887.673369798918, 10901.672949811518, 10926.617568385069, 8119.269265766109, 8306.210909963544, 8313.210245026712, 8326.043379805862, 8344.8331033379, 5101.285819985131, 5561.499465048165, 5839.532837373085, 5912.438318359749, 6066.423689749467, 8609.48343099416, 10151.898481015096, 10201.642942497274, 10329.0, 10339.63811266602, 9988.151007164472, 10172.542235599563, 10216.846747298649, 10340.12108970746, 10346.637867674595, 7026.437884969088, 7213.855722885618, 7351.963240183765, 7376.225496323023, 7605.201453847488, 8472.0, 8531.402801804063, 8541.615627296911, 8546.743597692079, 8560.186782255676, 7517.398608111447, 7588.734394296173, 7616.162222155448, 7706.961465192639, 7750.224977502205, 6170.753169873155, 7312.305330993547, 7434.665440055102, 7532.945387645844, 7559.924400755922, 6921.861562768616, 6934.514583979073, 7016.7544135954995, 7036.050133231948, 7051.894221586783, 4962.851114466572, 4991.301217829579, 5041.218611115283, 5138.640295179451, 5139.948600513947, 4598.965232822588, 4736.4316282046375, 5050.72221027847, 5180.870478238075, 5254.816081437131], "value": 4576.2925161782305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.15339588963235, 305.4059853584777, 311.863258423048, 313.7787859559011, 315.2874503621815, 4723.669343145981, 5063.848084557465, 5383.569314454844, 5536.667799932007, 5581.288443185277, 4853.107288157613, 5585.843998141047, 5656.660600363981, 5664.773409063643, 5682.602217844753, 4320.395144679747, 4328.567143285678, 4349.804258808361, 4350.825966961318, 4364.738115713068, 3552.769070010454, 3591.42537194049, 4106.568810274923, 4291.570842915715, 4336.913261734763, 3938.011304291343, 4217.894552636178, 4248.957510424887, 4276.401303817468, 4296.248156572603, 3812.0850995761093, 4292.1415716856445, 4309.482862056558, 4314.97842510787, 4329.328954012133, 3080.9845950770105, 3669.6513831185994, 3736.9626303736886, 3740.195857890539, 3761.930937984098, 4825.841797968497, 5076.543111120017, 5199.454057323965, 5522.3649280332875, 5524.254225679522, 4425.557444255581, 4445.177642136216, 4870.613399434287, 4895.192293271606, 4896.436909755388, 5456.317960254968, 5585.581081418869, 5665.915011274834, 5963.761449542013, 5989.574222439919, 3295.4624938797233, 3937.960620393788, 3972.344563147077, 3984.382420724792, 4030.9395359069636, 5146.588272938156, 5534.948359811623, 6034.336223015464, 6271.560990730651, 6344.492440604787, 3552.236269202134, 4046.575109613448, 4047.3119569673227, 4054.0675644601906, 4075.9592404076175, 3831.8275677594575, 3837.712171587142, 3894.597944739948, 4092.7339722917914, 4109.876703698894, 4892.779824907888, 4900.436449808247, 4911.312416261726, 4916.950830491721, 4931.679440836334, 5238.292830467914, 6019.860359956606, 6113.358097399798, 6131.14164017038, 6163.414475624809, 1353.35715535122, 1392.1786146173806, 1399.1185553101684, 1405.6204824697274, 1406.6413064668443, 832.7834762961674, 846.9328645906203, 858.772425307294, 862.9223369896739, 867.4361664917727, 4332.696711230246, 4508.48152462471, 4740.2178640523925, 4788.281757736298, 4821.011692603008, 4629.837955671535, 4754.215554433549, 4756.405449318835, 5236.947630523646, 5241.790328386898, 3815.828287727059, 4174.246816337146, 4523.982104026568, 4580.938577640075, 4592.0, 2676.37105280259, 2743.204470703474, 2912.5922370868097, 3188.6970737779875, 3311.8840840570465, 5692.629979051431, 5774.913376299359, 5946.70266486674, 5970.761169553256, 6117.785877494266, 5289.867753306198, 6039.48664363534, 6408.686219325027, 6409.903851442232, 6427.22873255203, 5062.0, 5576.386597474275, 6480.384363485461, 6502.512311576746, 6533.183352080895, 5726.9713651431475, 5925.555583331181, 6198.574327904517, 6286.0, 6347.730453909329, 5318.473724980758, 6189.628622282497, 6194.690265486617, 6231.348692596375, 6239.875202496016, 5919.792807251725, 6111.755529778759, 6543.934560654333, 6544.116544266653, 6582.222799844111, 3376.375370556455, 3796.772193668389, 4013.2776100302226, 4038.353863381845, 4052.615001574846, 5175.663581867167, 6182.0, 6206.820704066213, 6221.418111483684, 6312.506559908902, 5764.193012978102, 6073.240844894388, 6081.270247570143, 6109.450149486567, 6135.355787642234, 4459.687821852439, 5894.587378883437, 5949.762009519571, 5959.28488581373, 5960.761569537343, 4887.560119589254, 5373.9731301343245, 5457.263269458573, 5874.765009399747, 5912.526997840248, 4813.638977076804, 4912.70523768575, 4932.802687892444, 5990.281166260077, 6058.5153187745755, 5272.971770504868, 5811.883762324815, 5853.795117170878, 5855.0, 5869.484871792392], "value": 4224.077410812554, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.4300172846165, 700.1283402164306, 710.9466789990752, 723.5369363607291, 776.631100227392, 15485.458008969688, 15629.921850390761, 15866.68266634668, 15904.0, 15938.920305398457, 11412.0, 13892.81911037563, 14228.786568201485, 14339.0, 14412.135271883695, 669.3875104279579, 827.7640872351366, 835.3818174550819, 847.631280393031, 862.2584577263548, 825.6490991328661, 834.9248567628912, 852.0925214646381, 852.7740148860526, 852.9616167272487, 828.7596596986866, 859.8710193470966, 900.8648702694616, 902.5036230073466, 905.2079430498314, 862.038826708219, 888.3692578269437, 897.7261935109789, 899.2581120575519, 899.8965119011332, 660.2671035150978, 664.6311297230036, 665.4044630056121, 667.4226793823357, 669.8827705151627, 14811.703765924729, 14873.033252838532, 14932.47736329234, 14938.402463901431, 15032.0, 14505.927470362582, 14557.70884582313, 14559.199244041574, 14580.781288280685, 14654.706905861929, 11494.712632184262, 11511.827322590168, 11603.709907252301, 11706.297622142698, 11753.35356555389, 3168.493041113411, 3858.305505009097, 3881.7088718346236, 3895.2209558088493, 4022.0, 107245.92754072437, 110488.23779405499, 111917.44041279823, 112093.98825146856, 112470.43764781205, 11012.39431831241, 11049.116070714324, 11141.164412669083, 11160.441977901073, 11235.101191904629, 22402.439939001328, 22597.0961161555, 22639.434014149778, 22655.660165097535, 22710.545789084455, 7792.688292468351, 8031.959840200761, 8062.475939063942, 8063.758087257392, 8209.014918209854, 4375.978120109443, 4454.799534020977, 4496.437945256843, 4515.593596576324, 4678.742669153161, 3521.401361768454, 3633.9636603634153, 3636.363636363679, 3637.1634524059614, 3646.8176591170336, 646.715445204118, 665.4875745675803, 665.7569986954824, 674.4402146218724, 679.9116114905031, 598.8293336399105, 609.920710307666, 643.488426700774, 662.453475882399, 671.6943790575276, 9037.322200834833, 9464.763380915532, 9490.478023708762, 9600.519974001272, 9661.468619225869, 503.62983207342575, 533.6264614769676, 540.3111033432326, 574.6867956963465, 644.2172760096505, 4466.508684044752, 4591.563801438858, 4654.999175177285, 4685.835995740133, 4732.515429399593, 151210.24394878175, 162131.37868621398, 165042.0, 167746.93518388935, 171958.0, 98143.0, 126148.0, 132702.67297327096, 136765.0, 136899.0, 67471.3252867465, 108213.8357232851, 115228.42385788017, 119850.0, 128573.0, 8391.860075680663, 9826.5086745661, 9933.0, 10111.241656875616, 10154.644587439405, 55853.16220256781, 55875.0, 60448.39551604428, 66217.66891165513, 69514.0, 83175.584122079, 92840.53579732058, 106235.0, 106325.0, 108729.0, 10511.545982415879, 12328.630141095782, 12998.595259876953, 13577.456901723821, 14074.859251407765, 15048.849511504744, 15298.770518442456, 15312.310946007234, 15331.923340383226, 15402.998805077634, 13819.585412437644, 13859.307034648025, 14112.29438528049, 14182.716345672823, 14336.283185840875, 13979.370928308048, 14038.929805350908, 14073.296335183404, 14225.928870355581, 14294.428222870969, 11685.532578696757, 12719.491220351083, 14166.716665666836, 14271.574822042843, 14378.28108594587, 9748.171405430618, 11162.944185279022, 11296.43517824122, 11455.36995465224, 11797.941010294893, 9626.32615716893, 10374.792504149724, 10755.0, 10897.182711296842, 11882.16824822254], "value": 7844.7933109464875, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 115.0, 127.0, 120.0, 116.0, 117.0, 115.0, 116.0, 132.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 145.0, 133.0, 160.0, 110.0, 132.0, 134.0, 114.0, 166.0, 113.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 196.0, 204.0, 234.0, 194.0, 194.0, 197.0, 193.0, 196.0, 217.0], "value": 202.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 83.0, 78.0, 149.0, 59.0, 59.0, 63.0, 75.0, 158.0, 51.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 120.0, 73.0, 71.0, 84.0, 111.0, 94.0, 104.0, 145.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 86.0, 79.0, 112.0, 137.0, 87.0, 89.0, 86.0, 93.0, 86.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 96.0, 96.0, 119.0, 97.0, 153.0, 154.0, 97.0, 97.0, 102.0], "value": 114.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 121.0, 145.0, 88.0, 94.0, 81.0, 131.0, 124.0, 153.0], "value": 112.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 59.0, 57.0, 55.0, 49.0, 87.0, 52.0, 51.0, 61.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 68.0, 74.0, 90.0, 65.0, 94.0, 104.0, 104.0, 90.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 86.0, 90.0, 85.0, 90.0, 88.0, 89.0, 87.0, 98.0, 84.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 140.0, 141.0, 142.0, 141.0, 176.0, 137.0, 190.0, 140.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 153.0, 211.0, 245.0, 197.0, 191.0, 243.0, 210.0, 172.0, 173.0], "value": 196.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 91.0, 89.0, 96.0, 71.0, 68.0, 70.0, 67.0, 69.0, 70.0], "value": 76.7, "unit": "score"}]}]} 03:05:16 INFO - Return code: 0 03:05:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:05:16 ERROR - # TBPL SUCCESS # 03:05:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:05:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:05:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:05:16 INFO - 2016-09-08 03:05:16.953 screenresolution[1678:10534] starting screenresolution argv=screenresolution get 03:05:16 INFO - 2016-09-08 03:05:16.956 screenresolution[1678:10534] Display 0: 1600x1200x32@60 03:05:16 INFO - 2016-09-08 03:05:16.962 screenresolution[1679:10537] starting screenresolution argv=screenresolution list 03:05:16 INFO - Available Modes on Display 0 03:05:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:05:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:05:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:05:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:05:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:05:17 INFO - Intel Iris: 03:05:17 INFO - Chipset Model: Intel Iris 03:05:17 INFO - Type: GPU 03:05:17 INFO - Bus: Built-In 03:05:17 INFO - VRAM (Dynamic, Max): 1536 MB 03:05:17 INFO - Vendor: Intel (0x8086) 03:05:17 INFO - Device ID: 0x0a2e 03:05:17 INFO - Revision ID: 0x0009 03:05:17 INFO - Displays: 03:05:17 INFO - Display: 03:05:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:05:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:05:17 INFO - Display Serial Number: 1600x1200 60 03:05:17 INFO - Main Display: Yes 03:05:17 INFO - Mirror: Off 03:05:17 INFO - Online: Yes 03:05:17 INFO - Rotation: Supported 03:05:17 INFO - Return code: 0 03:05:17 INFO - Running post-action listener: _resource_record_post_action 03:05:17 INFO - [mozharness: 2016-09-08 10:05:17.198528Z] Finished run-tests step (success) 03:05:17 INFO - Running post-run listener: _resource_record_post_run 03:05:17 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37262336; Write bytes: 709839360; Read time: 247; Write time: 2008 03:05:17 INFO - TinderboxPrint: CPU usage
54.5% 03:05:17 INFO - TinderboxPrint: I/O read bytes / time
37,262,336 / 247 03:05:17 INFO - TinderboxPrint: I/O write bytes / time
709,839,360 / 2,008 03:05:17 INFO - TinderboxPrint: CPU idle
2,203.5 (45.5%) 03:05:17 INFO - TinderboxPrint: CPU system
979.0 (20.2%) 03:05:17 INFO - TinderboxPrint: CPU user
1,655.2 (34.2%) 03:05:17 INFO - TinderboxPrint: Swap in / out
442,789,888 / 0 03:05:17 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402586112; Write bytes: 397244416; Read time: 29784; Write time: 801 03:05:17 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34509824; Write bytes: 299831808; Read time: 224; Write time: 1170 03:05:17 INFO - Running post-run listener: _upload_blobber_files 03:05:17 INFO - Blob upload gear active. 03:05:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:05:17 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 03:05:17 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'] 03:05:17 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 03:05:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:05:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:19 INFO - (blobuploader) - INFO - Done attempting. 03:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:20 INFO - (blobuploader) - INFO - Done attempting. 03:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:05:22 INFO - (blobuploader) - INFO - Done attempting. 03:05:22 INFO - (blobuploader) - INFO - Iteration through files over. 03:05:22 INFO - Return code: 0 03:05:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:05:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:05:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52d7d318725bbd5943c1d6099a5052bf742a1d0f8368dda78f9591083f6b23f318e00826e2c216624d5843a3f977f9715b4883fd1908866e85cf4eb4238cf42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e6f5daab985d0a66f31c5689f7090599e07ddfef738fa5857c6810b035d474c9ded7326970dd353d35e3fe6daa389b3cc17704fda6102464b4bd234373f9015", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45701e0954dc3ee24447035043b207e9453cc291144a7fb6995bd8d8ded3d6cc2d52fb579a0579a0213604883190c0e33f619991134c52c36498accaa249055d"} 03:05:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:05:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:05:22 INFO - Contents: 03:05:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52d7d318725bbd5943c1d6099a5052bf742a1d0f8368dda78f9591083f6b23f318e00826e2c216624d5843a3f977f9715b4883fd1908866e85cf4eb4238cf42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e6f5daab985d0a66f31c5689f7090599e07ddfef738fa5857c6810b035d474c9ded7326970dd353d35e3fe6daa389b3cc17704fda6102464b4bd234373f9015", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45701e0954dc3ee24447035043b207e9453cc291144a7fb6995bd8d8ded3d6cc2d52fb579a0579a0213604883190c0e33f619991134c52c36498accaa249055d"} 03:05:22 INFO - Running post-run listener: copy_logs_to_upload_dir 03:05:22 INFO - Copying logs to upload dir... 03:05:22 INFO - mkdir: /builds/slave/test/build/upload/logs 03:05:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.569240 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-08 03:05:22.606616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 03:05:22.609763) ========= 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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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/52d7d318725bbd5943c1d6099a5052bf742a1d0f8368dda78f9591083f6b23f318e00826e2c216624d5843a3f977f9715b4883fd1908866e85cf4eb4238cf42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e6f5daab985d0a66f31c5689f7090599e07ddfef738fa5857c6810b035d474c9ded7326970dd353d35e3fe6daa389b3cc17704fda6102464b4bd234373f9015", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45701e0954dc3ee24447035043b207e9453cc291144a7fb6995bd8d8ded3d6cc2d52fb579a0579a0213604883190c0e33f619991134c52c36498accaa249055d"} build_url:https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010895 build_url: 'https://queue.taskcluster.net/v1/task/WErXzaZMT_ibL3BgSYCYhQ/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/52d7d318725bbd5943c1d6099a5052bf742a1d0f8368dda78f9591083f6b23f318e00826e2c216624d5843a3f977f9715b4883fd1908866e85cf4eb4238cf42b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e6f5daab985d0a66f31c5689f7090599e07ddfef738fa5857c6810b035d474c9ded7326970dd353d35e3fe6daa389b3cc17704fda6102464b4bd234373f9015", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45701e0954dc3ee24447035043b207e9453cc291144a7fb6995bd8d8ded3d6cc2d52fb579a0579a0213604883190c0e33f619991134c52c36498accaa249055d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-08 03:05:22.647085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:05:22.647688) ========= 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.zbGaHeZWmf/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.ZJzt3hdNEg/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.005086 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-08 03:05:22.696260) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 03:05:22.696599) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-08 03:05:22.696945) ========= ========= Total master_lag: 0.20 =========