builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0372 starttime: 1473422352.53 results: success (0) buildid: 20160909030428 builduid: c0f75244826048659cf530fc32c41176 revision: feff79e5b1374439f17c5ea10a559acf1380a8d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:12.525436) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:12.525889) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:12.526163) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:12.615441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:12.615702) ========= 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.6zkutDNDWR/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.5IRn02qX4z/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-09 04:59:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.51M=0.001s 2016-09-09 04:59:13 (8.51 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.616446 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:13.258601) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:13.258879) ========= 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.6zkutDNDWR/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.5IRn02qX4z/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.021333 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:13.307983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 04:59:13.308414) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev feff79e5b1374439f17c5ea10a559acf1380a8d5 --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 feff79e5b1374439f17c5ea10a559acf1380a8d5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6zkutDNDWR/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.5IRn02qX4z/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-09 04:59:13,421 truncating revision to first 12 chars 2016-09-09 04:59:13,422 Setting DEBUG logging. 2016-09-09 04:59:13,422 attempt 1/10 2016-09-09 04:59:13,422 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/feff79e5b137?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-09 04:59:14,321 unpacking tar archive at: mozilla-central-feff79e5b137/testing/mozharness/ program finished with exit code 0 elapsedTime=1.155421 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-09 04:59:14.487140) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:14.487463) ========= script_repo_revision: feff79e5b1374439f17c5ea10a559acf1380a8d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:14.487828) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:14.488506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-09 04:59:14.507506) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 04:59:14.507800) ========= /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.6zkutDNDWR/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.5IRn02qX4z/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59:14 INFO - MultiFileLogger online at 20160909 04:59:14 in /builds/slave/test 04:59:14 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 04:59:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:59:14 INFO - {'append_to_log': False, 04:59:14 INFO - 'base_work_dir': '/builds/slave/test', 04:59:14 INFO - 'blob_upload_branch': 'Firefox', 04:59:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:59:14 INFO - 'branch': 'Firefox', 04:59:14 INFO - 'buildbot_json_path': 'buildprops.json', 04:59:14 INFO - 'config_files': ('talos/mac_config.py',), 04:59:14 INFO - 'default_actions': ('clobber', 04:59:14 INFO - 'read-buildbot-config', 04:59:14 INFO - 'download-and-extract', 04:59:14 INFO - 'populate-webroot', 04:59:14 INFO - 'create-virtualenv', 04:59:14 INFO - 'install', 04:59:14 INFO - 'run-tests'), 04:59:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:59:14 INFO - 'download_minidump_stackwalk': True, 04:59:14 INFO - 'download_symbols': 'ondemand', 04:59:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:59:14 INFO - 'tooltool.py': '/tools/tooltool.py', 04:59:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:59:14 INFO - '/tools/misc-python/virtualenv.py')}, 04:59:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:59:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:59:14 INFO - 'installer_path': 'installer.exe', 04:59:14 INFO - 'log_level': 'info', 04:59:14 INFO - 'log_name': 'talos', 04:59:14 INFO - 'log_to_console': True, 04:59:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:59:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:59:14 INFO - 'opt_config_files': (), 04:59:14 INFO - 'pip_index': False, 04:59:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:14 INFO - 'cmd': ('bash', 04:59:14 INFO - '-c', 04:59:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:14 INFO - 'enabled': True, 04:59:14 INFO - 'halt_on_failure': False, 04:59:14 INFO - 'name': 'check_screen_resolution'},), 04:59:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:59:14 INFO - 'cmd': ('bash', 04:59:14 INFO - '-c', 04:59:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:59:14 INFO - 'enabled': True, 04:59:14 INFO - 'halt_on_failure': False, 04:59:14 INFO - 'name': 'check_screen_resolution'},), 04:59:14 INFO - 'run_cmd_checks_enabled': True, 04:59:14 INFO - 'sps_profile': False, 04:59:14 INFO - 'sps_profile_interval': 0, 04:59:14 INFO - 'suite': 'dromaeojs-e10s', 04:59:14 INFO - 'system_bits': '32', 04:59:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:59:14 INFO - 'title': 't-yosemite-r7-0372', 04:59:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:59:14 INFO - 'use_talos_json': True, 04:59:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:59:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:59:14 INFO - 'work_dir': 'build'} 04:59:14 INFO - [mozharness: 2016-09-09 11:59:14.734267Z] Running clobber step. 04:59:14 INFO - Running pre-action listener: _resource_record_pre_action 04:59:14 INFO - Running main action method: clobber 04:59:14 INFO - rmtree: /builds/slave/test/build 04:59:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:59:16 INFO - Running post-action listener: _resource_record_post_action 04:59:16 INFO - [mozharness: 2016-09-09 11:59:16.750607Z] Finished clobber step (success) 04:59:16 INFO - [mozharness: 2016-09-09 11:59:16.750893Z] Running read-buildbot-config step. 04:59:16 INFO - Running pre-action listener: _resource_record_pre_action 04:59:16 INFO - Running main action method: read_buildbot_config 04:59:16 INFO - Using buildbot properties: 04:59:16 INFO - { 04:59:16 INFO - "project": "", 04:59:16 INFO - "product": "firefox", 04:59:16 INFO - "script_repo_revision": "production", 04:59:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:59:16 INFO - "repository": "", 04:59:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:59:16 INFO - "buildid": "20160909030428", 04:59:16 INFO - "pgo_build": "False", 04:59:16 INFO - "basedir": "/builds/slave/test", 04:59:16 INFO - "buildnumber": 64, 04:59:16 INFO - "slavename": "t-yosemite-r7-0372", 04:59:16 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:59:16 INFO - "platform": "yosemite_r7", 04:59:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:59:16 INFO - "branch": "mozilla-central", 04:59:16 INFO - "repo_path": "mozilla-central", 04:59:16 INFO - "revision": "feff79e5b1374439f17c5ea10a559acf1380a8d5", 04:59:16 INFO - "stage_platform": "macosx64", 04:59:16 INFO - "builduid": "c0f75244826048659cf530fc32c41176", 04:59:16 INFO - "slavebuilddir": "test" 04:59:16 INFO - } 04:59:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:59:16 INFO - Running post-action listener: _resource_record_post_action 04:59:16 INFO - [mozharness: 2016-09-09 11:59:16.756457Z] Finished read-buildbot-config step (success) 04:59:16 INFO - [mozharness: 2016-09-09 11:59:16.756682Z] Running download-and-extract step. 04:59:16 INFO - Running pre-action listener: _resource_record_pre_action 04:59:16 INFO - Running main action method: download_and_extract 04:59:16 INFO - mkdir: /builds/slave/test/build/tests 04:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:16 INFO - trying https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:59:16 INFO - Downloading https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/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 04:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/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 04:59:18 INFO - Downloaded 1199 bytes. 04:59:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:59:18 INFO - Using the following test package requirements: 04:59:18 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:59:18 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:59:18 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:59:18 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'jsshell-mac.zip'], 04:59:18 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:59:18 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:59:18 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:59:18 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:59:18 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:59:18 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:59:18 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:59:18 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:59:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:59:18 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:59:25 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 04:59:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:59:26 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:59:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:59:33 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 04:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:33 INFO - trying https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:59:33 INFO - Downloading https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:59:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/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 04:59:37 INFO - Downloaded 111597534 bytes. 04:59:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:59:37 INFO - mkdir: /builds/slave/test/properties 04:59:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:59:37 INFO - Writing to file /builds/slave/test/properties/build_url 04:59:37 INFO - Contents: 04:59:37 INFO - build_url:https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:59:38 INFO - Running post-action listener: _resource_record_post_action 04:59:38 INFO - Running post-action listener: set_extra_try_arguments 04:59:38 INFO - [mozharness: 2016-09-09 11:59:38.929736Z] Finished download-and-extract step (success) 04:59:38 INFO - [mozharness: 2016-09-09 11:59:38.929838Z] Running populate-webroot step. 04:59:38 INFO - Running pre-action listener: _resource_record_pre_action 04:59:38 INFO - Running main action method: populate_webroot 04:59:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:59:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:59:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:59:38 INFO - '--tppagecycles', 04:59:38 INFO - '7'], 04:59:38 INFO - 'tests': ['tsvgm']}}, 04:59:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:59:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:59:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:59:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:59:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:59:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:59:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['damp', 'tps']}, 04:59:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'tests': ['damp', 'tps']}, 04:59:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['dromaeo_dom']}, 04:59:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:59:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['basic_compositor_video']}, 04:59:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:59:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['a11yr', 04:59:38 INFO - 'ts_paint', 04:59:38 INFO - 'tpaint', 04:59:38 INFO - 'sessionrestore', 04:59:38 INFO - 'sessionrestore_no_auto_restore', 04:59:38 INFO - 'tabpaint']}, 04:59:38 INFO - 'other-e10s': {'tests': ['a11yr', 04:59:38 INFO - 'ts_paint', 04:59:38 INFO - 'tpaint', 04:59:38 INFO - 'sessionrestore', 04:59:38 INFO - 'sessionrestore_no_auto_restore', 04:59:38 INFO - 'tabpaint']}, 04:59:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:59:38 INFO - 'ts_paint', 04:59:38 INFO - 'tpaint', 04:59:38 INFO - 'sessionrestore', 04:59:38 INFO - 'sessionrestore_no_auto_restore', 04:59:38 INFO - 'tabpaint']}, 04:59:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:59:38 INFO - 'ts_paint', 04:59:38 INFO - 'tpaint', 04:59:38 INFO - 'sessionrestore', 04:59:38 INFO - 'sessionrestore_no_auto_restore', 04:59:38 INFO - 'tabpaint']}, 04:59:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['a11yr', 04:59:38 INFO - 'ts_paint', 04:59:38 INFO - 'tpaint', 04:59:38 INFO - 'sessionrestore', 04:59:38 INFO - 'sessionrestore_no_auto_restore', 04:59:38 INFO - 'tabpaint']}, 04:59:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['a11yr', 04:59:38 INFO - 'ts_paint', 04:59:38 INFO - 'tpaint', 04:59:38 INFO - 'sessionrestore', 04:59:38 INFO - 'sessionrestore_no_auto_restore', 04:59:38 INFO - 'tabpaint']}, 04:59:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['tsvgx', 04:59:38 INFO - 'tsvgr_opacity', 04:59:38 INFO - 'tart', 04:59:38 INFO - 'tscrollx', 04:59:38 INFO - 'cart']}, 04:59:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:59:38 INFO - 'tsvgr_opacity', 04:59:38 INFO - 'tart', 04:59:38 INFO - 'tscrollx', 04:59:38 INFO - 'cart']}, 04:59:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'talos_options': ['--disable-e10s'], 04:59:38 INFO - 'tests': ['tp5o']}, 04:59:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'tests': ['tp5o']}, 04:59:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'talos_options': ['--disable-e10s', 04:59:38 INFO - '--xperf_path', 04:59:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:59:38 INFO - 'tests': ['tp5n']}, 04:59:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:59:38 INFO - 'talos_options': ['--xperf_path', 04:59:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:59:38 INFO - 'tests': ['tp5n']}}, 04:59:38 INFO - 'talos.zip': {'path': '', 04:59:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:59:38 INFO - Running post-action listener: _resource_record_post_action 04:59:38 INFO - [mozharness: 2016-09-09 11:59:38.948523Z] Finished populate-webroot step (success) 04:59:38 INFO - [mozharness: 2016-09-09 11:59:38.948660Z] Running create-virtualenv step. 04:59:38 INFO - Running pre-action listener: _resource_record_pre_action 04:59:38 INFO - Running main action method: create_virtualenv 04:59:38 INFO - Creating virtualenv /builds/slave/test/build/venv 04:59:38 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 04:59:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:59:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:59:39 INFO - Using real prefix '/tools/python27' 04:59:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:59:39 INFO - Installing distribute.............................................................................................................................................................................................done. 04:59:42 INFO - Installing pip.................done. 04:59:42 INFO - Return code: 0 04:59:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:42 INFO - 'HOME': '/Users/cltbld', 04:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:42 INFO - 'LOGNAME': 'cltbld', 04:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:42 INFO - 'MOZ_NO_REMOTE': '1', 04:59:42 INFO - 'NO_EM_RESTART': '1', 04:59:42 INFO - 'PAGER': '/bin/cat', 04:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:42 INFO - 'PWD': '/builds/slave/test', 04:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:42 INFO - 'SHELL': '/bin/bash', 04:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:42 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:42 INFO - 'USER': 'cltbld', 04:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:42 INFO - 'XPC_FLAGS': '0x0', 04:59:42 INFO - 'XPC_SERVICE_NAME': '0', 04:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:42 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 04:59:42 INFO - Cleaning up... 04:59:42 INFO - Return code: 0 04:59:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:42 INFO - 'HOME': '/Users/cltbld', 04:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:42 INFO - 'LOGNAME': 'cltbld', 04:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:42 INFO - 'MOZ_NO_REMOTE': '1', 04:59:42 INFO - 'NO_EM_RESTART': '1', 04:59:42 INFO - 'PAGER': '/bin/cat', 04:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:42 INFO - 'PWD': '/builds/slave/test', 04:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:42 INFO - 'SHELL': '/bin/bash', 04:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:42 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:42 INFO - 'USER': 'cltbld', 04:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:42 INFO - 'XPC_FLAGS': '0x0', 04:59:42 INFO - 'XPC_SERVICE_NAME': '0', 04:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:43 INFO - Downloading/unpacking psutil>=3.1.1 04:59:43 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 04:59:43 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 04:59:43 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 04:59:43 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 04:59:43 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 04:59:43 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 04:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:59:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:59:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:59:46 INFO - Installing collected packages: psutil 04:59:46 INFO - Running setup.py install for psutil 04:59:46 INFO - building 'psutil._psutil_osx' extension 04:59:46 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 04:59:46 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 04:59:47 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 04:59:47 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 04:59:47 INFO - building 'psutil._psutil_posix' extension 04:59:47 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 04:59:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:59:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:59:47 INFO - ^ 04:59:47 INFO - 1 warning generated. 04:59:47 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 04:59:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:59:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:59:47 INFO - Successfully installed psutil 04:59:47 INFO - Cleaning up... 04:59:47 INFO - Return code: 0 04:59:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:59: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')]} 04:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59: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')]} 04:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:59: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 mozsystemmonitor==0.3 04:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:47 INFO - 'HOME': '/Users/cltbld', 04:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:47 INFO - 'LOGNAME': 'cltbld', 04:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:47 INFO - 'MOZ_NO_REMOTE': '1', 04:59:47 INFO - 'NO_EM_RESTART': '1', 04:59:47 INFO - 'PAGER': '/bin/cat', 04:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:47 INFO - 'PWD': '/builds/slave/test', 04:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:47 INFO - 'SHELL': '/bin/bash', 04:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:47 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:47 INFO - 'USER': 'cltbld', 04:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:47 INFO - 'XPC_FLAGS': '0x0', 04:59:47 INFO - 'XPC_SERVICE_NAME': '0', 04:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:59:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:59:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:59:50 INFO - Installing collected packages: mozsystemmonitor 04:59:50 INFO - Running setup.py install for mozsystemmonitor 04:59:50 INFO - Successfully installed mozsystemmonitor 04:59:50 INFO - Cleaning up... 04:59:50 INFO - Return code: 0 04:59:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:59: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')]} 04:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59: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')]} 04:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59: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', '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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:59: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 jsonschema==2.5.1 04:59:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:50 INFO - 'HOME': '/Users/cltbld', 04:59:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:50 INFO - 'LOGNAME': 'cltbld', 04:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:50 INFO - 'MOZ_NO_REMOTE': '1', 04:59:50 INFO - 'NO_EM_RESTART': '1', 04:59:50 INFO - 'PAGER': '/bin/cat', 04:59:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:50 INFO - 'PWD': '/builds/slave/test', 04:59:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:50 INFO - 'SHELL': '/bin/bash', 04:59:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:50 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:50 INFO - 'USER': 'cltbld', 04:59:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:50 INFO - 'XPC_FLAGS': '0x0', 04:59:50 INFO - 'XPC_SERVICE_NAME': '0', 04:59:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:50 INFO - Downloading/unpacking jsonschema==2.5.1 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:59:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:53 INFO - Downloading functools32-3.2.3-2.tar.gz 04:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:59:53 INFO - warning: no files found matching '*.txt' 04:59:53 INFO - no previously-included directories found matching 'build' 04:59:53 INFO - no previously-included directories found matching 'dist' 04:59:53 INFO - no previously-included directories found matching '.git*' 04:59:53 INFO - Installing collected packages: jsonschema, functools32 04:59:53 INFO - Running setup.py install for functools32 04:59:53 INFO - warning: no files found matching '*.txt' 04:59:53 INFO - no previously-included directories found matching 'build' 04:59:53 INFO - no previously-included directories found matching 'dist' 04:59:53 INFO - no previously-included directories found matching '.git*' 04:59:53 INFO - Successfully installed jsonschema functools32 04:59:53 INFO - Cleaning up... 04:59:53 INFO - Return code: 0 04:59:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:59:53 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')]} 04:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:53 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')]} 04:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:53 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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59:53 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 04:59:53 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 04:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:53 INFO - 'HOME': '/Users/cltbld', 04:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:53 INFO - 'LOGNAME': 'cltbld', 04:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:53 INFO - 'MOZ_NO_REMOTE': '1', 04:59:53 INFO - 'NO_EM_RESTART': '1', 04:59:53 INFO - 'PAGER': '/bin/cat', 04:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:53 INFO - 'PWD': '/builds/slave/test', 04:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:53 INFO - 'SHELL': '/bin/bash', 04:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:53 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:53 INFO - 'USER': 'cltbld', 04:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:53 INFO - 'XPC_FLAGS': '0x0', 04:59:53 INFO - 'XPC_SERVICE_NAME': '0', 04:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:59:54 INFO - Cleaning up... 04:59:54 INFO - Return code: 0 04:59:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:54 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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59:54 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 04:59:54 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 04:59:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:54 INFO - 'HOME': '/Users/cltbld', 04:59:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:54 INFO - 'LOGNAME': 'cltbld', 04:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:54 INFO - 'MOZ_NO_REMOTE': '1', 04:59:54 INFO - 'NO_EM_RESTART': '1', 04:59:54 INFO - 'PAGER': '/bin/cat', 04:59:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:54 INFO - 'PWD': '/builds/slave/test', 04:59:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:54 INFO - 'SHELL': '/bin/bash', 04:59:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:54 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:54 INFO - 'USER': 'cltbld', 04:59:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:54 INFO - 'XPC_FLAGS': '0x0', 04:59:54 INFO - 'XPC_SERVICE_NAME': '0', 04:59:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:54 INFO - Downloading/unpacking blobuploader==1.2.4 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59:56 INFO - Downloading blobuploader-1.2.4.tar.gz 04:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:59:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:59:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 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 04:59:57 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 04:59:58 INFO - Downloading docopt-0.6.1.tar.gz 04:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:59:58 INFO - Installing collected packages: blobuploader, requests, docopt 04:59:58 INFO - Running setup.py install for blobuploader 04:59:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:59:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:59:58 INFO - Running setup.py install for requests 04:59:58 INFO - Running setup.py install for docopt 04:59:58 INFO - Successfully installed blobuploader requests docopt 04:59:58 INFO - Cleaning up... 04:59:59 INFO - Return code: 0 04:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:59 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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 04:59:59 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 04:59:59 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 04:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 04:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:59 INFO - 'HOME': '/Users/cltbld', 04:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:59 INFO - 'LOGNAME': 'cltbld', 04:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:59 INFO - 'MOZ_NO_REMOTE': '1', 04:59:59 INFO - 'NO_EM_RESTART': '1', 04:59:59 INFO - 'PAGER': '/bin/cat', 04:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:59 INFO - 'PWD': '/builds/slave/test', 04:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:59 INFO - 'SHELL': '/bin/bash', 04:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 04:59:59 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 04:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:59 INFO - 'USER': 'cltbld', 04:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:59 INFO - 'XPC_FLAGS': '0x0', 04:59:59 INFO - 'XPC_SERVICE_NAME': '0', 04:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Fa_rDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-7h82a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-vtCO6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-DmJzMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-q59khV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-5OJ3se-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:59:59 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-jjVEVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Mj_Fld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-qPYVef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-5Oqmn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-8oztG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-arr5Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Ma1E8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-hDq0jA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-LuVrdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:00 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-KXUEUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:01 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-EeQEl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:00:01 INFO - Running setup.py install for manifestparser 05:00:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:00:01 INFO - Running setup.py install for mozcrash 05:00:01 INFO - Running setup.py install for mozdebug 05:00:01 INFO - Running setup.py install for mozdevice 05:00:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:00:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:00:01 INFO - Running setup.py install for mozfile 05:00:01 INFO - Running setup.py install for mozhttpd 05:00:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Running setup.py install for mozinfo 05:00:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Running setup.py install for mozInstall 05:00:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Running setup.py install for mozleak 05:00:02 INFO - Running setup.py install for mozlog 05:00:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Running setup.py install for moznetwork 05:00:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:00:02 INFO - Running setup.py install for mozprocess 05:00:02 INFO - Running setup.py install for mozprofile 05:00:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:00:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:00:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:00:03 INFO - Running setup.py install for mozrunner 05:00:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:00:03 INFO - Running setup.py install for mozscreenshot 05:00:03 INFO - Running setup.py install for moztest 05:00:03 INFO - Running setup.py install for mozversion 05:00:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:00:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:00:03 INFO - Cleaning up... 05:00:03 INFO - Return code: 0 05:00:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:03 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')]} 05:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:03 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')]} 05:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:03 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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 05:00:03 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 05:00:03 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 05:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 05:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:03 INFO - 'HOME': '/Users/cltbld', 05:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:03 INFO - 'LOGNAME': 'cltbld', 05:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:03 INFO - 'MOZ_NO_REMOTE': '1', 05:00:03 INFO - 'NO_EM_RESTART': '1', 05:00:03 INFO - 'PAGER': '/bin/cat', 05:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:03 INFO - 'PWD': '/builds/slave/test', 05:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:03 INFO - 'SHELL': '/bin/bash', 05:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 05:00:03 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 05:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:03 INFO - 'USER': 'cltbld', 05:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:03 INFO - 'XPC_FLAGS': '0x0', 05:00:03 INFO - 'XPC_SERVICE_NAME': '0', 05:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:00:03 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-r_8eBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-XrK5SY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-h10RYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-KajuC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-N_LEm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-CF06W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-MtgCKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Lz4KHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Rvq2NC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:00:04 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)) 05:00:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:00:04 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-BPwsZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-tqrozi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-MiHIh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-OFz24Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-mY7tE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-ROaTU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-vUIOHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:00:05 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)) 05:00:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:00:05 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-J4DjYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:00:05 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)) 05:00:05 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)) 05:00:05 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)) 05:00:05 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)) 05:00:05 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)) 05:00:05 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)) 05:00:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:00:08 INFO - Downloading blessings-1.6.tar.gz 05:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:00:08 INFO - Installing collected packages: blessings 05:00:08 INFO - Running setup.py install for blessings 05:00:08 INFO - Successfully installed blessings 05:00:08 INFO - Cleaning up... 05:00:08 INFO - Return code: 0 05:00:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:08 INFO - Reading from file tmpfile_stdout 05:00:08 INFO - Current package versions: 05:00:08 INFO - blessings == 1.6 05:00:08 INFO - blobuploader == 1.2.4 05:00:08 INFO - docopt == 0.6.1 05:00:08 INFO - functools32 == 3.2.3-2 05:00:08 INFO - jsonschema == 2.5.1 05:00:08 INFO - manifestparser == 1.1 05:00:08 INFO - mozInstall == 1.12 05:00:08 INFO - mozcrash == 0.17 05:00:08 INFO - mozdebug == 0.1 05:00:08 INFO - mozdevice == 0.48 05:00:08 INFO - mozfile == 1.2 05:00:08 INFO - mozhttpd == 0.7 05:00:08 INFO - mozinfo == 0.9 05:00:08 INFO - mozleak == 0.1 05:00:08 INFO - mozlog == 3.3 05:00:08 INFO - moznetwork == 0.27 05:00:08 INFO - mozprocess == 0.23 05:00:08 INFO - mozprofile == 0.28 05:00:08 INFO - mozrunner == 6.12 05:00:08 INFO - mozscreenshot == 0.1 05:00:08 INFO - mozsystemmonitor == 0.3 05:00:08 INFO - moztest == 0.7 05:00:08 INFO - mozversion == 1.4 05:00:08 INFO - psutil == 3.1.1 05:00:08 INFO - requests == 1.2.3 05:00:08 INFO - wsgiref == 0.1.2 05:00:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:00:08 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')]} 05:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:08 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')]} 05:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:08 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 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 05:00:08 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 05:00:08 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 05:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 05:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:08 INFO - 'HOME': '/Users/cltbld', 05:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:08 INFO - 'LOGNAME': 'cltbld', 05:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:08 INFO - 'MOZ_NO_REMOTE': '1', 05:00:08 INFO - 'NO_EM_RESTART': '1', 05:00:08 INFO - 'PAGER': '/bin/cat', 05:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:08 INFO - 'PWD': '/builds/slave/test', 05:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:08 INFO - 'SHELL': '/bin/bash', 05:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 05:00:08 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 05:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:08 INFO - 'USER': 'cltbld', 05:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:08 INFO - 'XPC_FLAGS': '0x0', 05:00:08 INFO - 'XPC_SERVICE_NAME': '0', 05:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 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)) 05:00:09 INFO - Cleaning up... 05:00:09 INFO - Return code: 0 05:00:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f484a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f519618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba6a582f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, '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.6zkutDNDWR/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.5IRn02qX4z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 05:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 05:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:09 INFO - 'HOME': '/Users/cltbld', 05:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:09 INFO - 'LOGNAME': 'cltbld', 05:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:09 INFO - 'MOZ_NO_REMOTE': '1', 05:00:09 INFO - 'NO_EM_RESTART': '1', 05:00:09 INFO - 'PAGER': '/bin/cat', 05:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:09 INFO - 'PWD': '/builds/slave/test', 05:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:09 INFO - 'SHELL': '/bin/bash', 05:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 05:00:09 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 05:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:09 INFO - 'USER': 'cltbld', 05:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:09 INFO - 'XPC_FLAGS': '0x0', 05:00:09 INFO - 'XPC_SERVICE_NAME': '0', 05:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:00:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:00:09 INFO - Cleaning up... 05:00:09 INFO - Return code: 0 05:00:09 INFO - Running post-action listener: _resource_record_post_action 05:00:09 INFO - Running post-action listener: _start_resource_monitoring 05:00:09 INFO - Starting resource monitoring. 05:00:09 INFO - [mozharness: 2016-09-09 12:00:09.374658Z] Finished create-virtualenv step (success) 05:00:09 INFO - [mozharness: 2016-09-09 12:00:09.375733Z] Running install step. 05:00:09 INFO - Running pre-action listener: _resource_record_pre_action 05:00:09 INFO - Running main action method: install 05:00:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:00:09 INFO - Reading from file tmpfile_stdout 05:00:09 INFO - Detecting whether we're running mozinstall >=1.0... 05:00:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:00:09 INFO - Reading from file tmpfile_stdout 05:00:09 INFO - Output received: 05:00:09 INFO - Usage: mozinstall [options] installer 05:00:09 INFO - Options: 05:00:09 INFO - -h, --help show this help message and exit 05:00:09 INFO - -d DEST, --destination=DEST 05:00:09 INFO - Directory to install application into. [default: 05:00:09 INFO - "/builds/slave/test"] 05:00:09 INFO - --app=APP Application being installed. [default: firefox] 05:00:09 INFO - mkdir: /builds/slave/test/build/application 05:00:09 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'] 05:00:09 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 05:00:44 INFO - Reading from file tmpfile_stdout 05:00:44 INFO - Output received: 05:00:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:00:44 INFO - Running post-action listener: _resource_record_post_action 05:00:44 INFO - [mozharness: 2016-09-09 12:00:44.179131Z] Finished install step (success) 05:00:44 INFO - [mozharness: 2016-09-09 12:00:44.179307Z] Running run-tests step. 05:00:44 INFO - Running pre-action listener: _resource_record_pre_action 05:00:44 INFO - Running main action method: run_tests 05:00:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:00:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:00:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:00:44 INFO - 2016-09-09 05:00:44.194 screenresolution[1941:17965] starting screenresolution argv=screenresolution get 05:00:44 INFO - 2016-09-09 05:00:44.196 screenresolution[1941:17965] Display 0: 1600x1200x32@60 05:00:44 INFO - 2016-09-09 05:00:44.203 screenresolution[1942:17967] starting screenresolution argv=screenresolution list 05:00:44 INFO - Available Modes on Display 0 05:00:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:00:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:00:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:00:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:00:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:00:44 INFO - Intel Iris: 05:00:44 INFO - Chipset Model: Intel Iris 05:00:44 INFO - Type: GPU 05:00:44 INFO - Bus: Built-In 05:00:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:00:44 INFO - Vendor: Intel (0x8086) 05:00:44 INFO - Device ID: 0x0a2e 05:00:44 INFO - Revision ID: 0x0009 05:00:44 INFO - Displays: 05:00:44 INFO - Display: 05:00:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:00:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:00:44 INFO - Display Serial Number: 1600x1200 60 05:00:44 INFO - Main Display: Yes 05:00:44 INFO - Mirror: Off 05:00:44 INFO - Online: Yes 05:00:44 INFO - Rotation: Supported 05:00:44 INFO - Return code: 0 05:00:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:00:44 INFO - Python 2.7.3 05:00:44 INFO - Return code: 0 05:00:44 INFO - grabbing minidump binary from tooltool 05:00: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')]} 05:00:44 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 0x10f3e8d30>, '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 0x10f14ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:00:44 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 05:00:44 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 05:00:44 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 05:00:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:00:44 INFO - Return code: 0 05:00:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:00:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:00:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:00:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:00:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:00:44 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/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--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 05:00:44 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/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0372 --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 05:00:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zkutDNDWR/Render', 05:00:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:00:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:00:44 INFO - 'HOME': '/Users/cltbld', 05:00:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:00:44 INFO - 'LOGNAME': 'cltbld', 05:00:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:00:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:00:44 INFO - 'MOZ_NO_REMOTE': '1', 05:00:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:00:44 INFO - 'NO_EM_RESTART': '1', 05:00:44 INFO - 'PAGER': '/bin/cat', 05:00:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:00:44 INFO - 'PWD': '/builds/slave/test', 05:00:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:00:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:00:44 INFO - 'SHELL': '/bin/bash', 05:00:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5IRn02qX4z/Listeners', 05:00:44 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 05:00:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:00:44 INFO - 'USER': 'cltbld', 05:00:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:00:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:00:44 INFO - 'XPC_FLAGS': '0x0', 05:00:44 INFO - 'XPC_SERVICE_NAME': '0', 05:00:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:00:44 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/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--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 05:00:44 INFO - mozversion application_buildid: 20160909030428 05:00:44 INFO - mozversion application_changeset: feff79e5b1374439f17c5ea10a559acf1380a8d5 05:00:44 INFO - mozversion application_display_name: Nightly 05:00:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:00:44 INFO - mozversion application_name: Firefox 05:00:44 INFO - mozversion application_remotingname: firefox 05:00:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:00:44 INFO - mozversion application_vendor: Mozilla 05:00:44 INFO - mozversion application_version: 51.0a1 05:00:44 INFO - mozversion platform_buildid: 20160909030428 05:00:44 INFO - mozversion platform_changeset: feff79e5b1374439f17c5ea10a559acf1380a8d5 05:00:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:00:44 INFO - mozversion platform_version: 51.0a1 05:00:44 INFO - using testdate: 1473422444 05:00:44 INFO - actual date: 1473422444 05:00:44 INFO - starting webserver on 'localhost:56063' 05:00:44 INFO - SUITE-START | Running 2 tests 05:00:44 INFO - TEST-START | dromaeo_css 05:00:44 INFO - operating with platform_type : mac_ 05:00:44 INFO - Initialising browser for dromaeo_css test... 05:00:44 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpdMmjPZ/profile http://localhost:56063/getInfo.html) 05:00:47 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 05:00:47 INFO - PROCESS | 1952 | colorDepth:24 05:00:47 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 05:00:47 INFO - PROCESS | 1952 | __metrics 05:00:47 ERROR - PROCESS | 1952 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 05:00:48 INFO - TEST-INFO | 1952: exit 0 05:00:48 INFO - Browser initialized. 05:00:48 INFO - Running cycle 1/1 for dromaeo_css test... 05:00:48 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpdMmjPZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:01:00 INFO - PROCESS | 1955 | RSS: Main: 244297728 05:01:00 INFO - PROCESS | 1955 | 05:01:50 INFO - PROCESS | 1955 | 1473422510117 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] 05:04:02 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:56063/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56063/tests/dromaeo/cssquery-ext.html) 05:04:03 INFO - PROCESS | 1955 | RSS: Main: 262619136 05:04:03 INFO - PROCESS | 1955 | 05:07:06 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:56063/tests/dromaeo/cssquery-ext.html (next: http://localhost:56063/tests/dromaeo/cssquery-jquery.html) 05:07:06 INFO - PROCESS | 1955 | RSS: Main: 270299136 05:07:06 INFO - PROCESS | 1955 | 05:10:08 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:56063/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56063/tests/dromaeo/cssquery-mootools.html) 05:10:09 INFO - PROCESS | 1955 | RSS: Main: 276164608 05:10:09 INFO - PROCESS | 1955 | 05:13:11 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:56063/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56063/tests/dromaeo/cssquery-prototype.html) 05:13:11 INFO - PROCESS | 1955 | RSS: Main: 271454208 05:13:11 INFO - PROCESS | 1955 | 05:16:14 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:56063/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56063/tests/dromaeo/cssquery-yui.html) 05:16:15 INFO - PROCESS | 1955 | RSS: Main: 277131264 05:16:15 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:56063/tests/dromaeo/cssquery-yui.html (next: http://localhost:56063/tests/dromaeo/cssquery-dojo.html) 05:19:18 INFO - PROCESS | 1955 | RSS: Main: 280190976 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | __start_tp_report 05:19:18 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 05:19:18 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 05:19:18 INFO - PROCESS | 1955 | |i|pagename|runs| 05:19:18 INFO - PROCESS | 1955 | |0;dojo.html;2848.416074704685;3293.901182964511;3396.7622266441344;3399.966000339996;3478.513008178854;5705.48650621444;5746.138079288109;5751.7986870459545;5824.621399609023;5859.648421094732;5756.395578464264;5845.181674565553;5881.059030555112;5891.587588868781;6021.277446706401;5239.1093514102595;5358.91961620576;5384.300040994684;5421.620486565942;5430.728463576826;5190.688558686472;5344.331958505187;5403.78384864605;5410.377806552237;5434.3207099112615;5198.428172900959;5244.370675518924;5266.552343050846;5280.313559237313;5305.1776974569;5221.973890130525;5271.657342272779;5294.470552944713;5320.65415747979;5336.146216605363;5284.07528682479;5307.097793375136;5334.759935802898;5337.172738225581;5352.197170424429;5499.862503437406;5758.654480731169;5821.126830975345;5902.321233058167;5910.7931222407005;6231.937680623227;6267.905981410283;6281.905771413432;6320.083587879778;6338.239411270677;5302.549283310923;6178.289496707891;6232.1898153240245;6261.686915654199;6309.526785491106;4819.831305904277;5939.613925094855;5995.730192141407;6036.6679832609625;6091.66495842724;6369.331220221903;6429.710663020175;6440.645764482904;6441.291457939623;6452.1289625900845;6111.297200821962;6242.906356404657;6246.968765156145;6260.24877014752;6292.559520833498;6161.291451483047;6223.346548612421;6255.155554000243;6268.811935641938;6294.464970477561;6150.661713605795;6264.373562643699;6284.12022316876;6285.654289014148;6290.182276304051;6187.195664563668;6284.371562843679;6307.24313082433;6329.082283068929;6342.048692696132;4225.471816022997;4885.022995400934;4958.553730164303;4965.528274813887;5056.317397151411;4454.977725111418;4509.075639493896;4540.954590454119;4562.224421848294;4587.977060114678;5952.345242023374;6056.273247210275;6064.636121832704;6069.15031895535;6108.236470441195;6132.906245166601;6221.595596286227;6230.657313847782;6233.625982441068;6292.276388215322;5970.761169553256;6031.336552979169;6033.336333003366;6062.181605483292;6080.48315893154;5523.337199536081;5525.4474552544425;5527.668339899538;5537.390887002426;5550.111982082847;6277.780277690259;6327.778527751507;6409.9679501602195;6514.609123452608;6522.902156467657;5469.863253418696;6287;6290.182276304051;6306.0225665022;6326.557141000086;6505.73977040932;6509.381608747161;6515.54391192612;6530.771423000172;6550.901736474052;4975.5522003019905;6200.658963756856;6212.0371342441995;6251.6874156292915;6281.937180628318;4775.594074503567;6207.565470417027;6219.751209951733;6301.180846489927;6307.716152773228;6055.273367195965;6208.099825525183;6235.812925612239;6255.437010669061;6264.185655864891;5125.897482050413;5210.583153347648;5275.208718692227;5797.391273916178;6013.939860601338;5842.912356314744;5971.7611295547695;6060.818175454743;6107.144999700135;6135.753292002278;6116.969415152896;6179.104029915727;6192.907106393498;6207.844803879939;6227.19046523949;5285.339332583427;5964.165016897551;5987.820365389045;6136.815895523141;6168.537359698131 05:19:18 INFO - PROCESS | 1955 | |1;ext.html;457.3574128349668;478.6888522460398;497.98008079676816;525.5270256768908;533.1789044870899;38002.42996355054;39844.40233396494;39914.40128398076;41913.580864191346;41957.7902110489;40961.180776384455;41152;41460.79269603648;41633.75098747035;41711.791441042755;9654.469004204766;9806.65676701312;9831.705048848546;9840.753981150492;9860.457674827881;9758.463284519348;9874.654387096452;9888.11007009369;9894.9505252474;9901;9854.113129818315;9856.753581160458;9898.010198980117;9903.10872021518;9916.008399160099;9833.065858743445;9842.212622990144;9845.950770246174;9863.50682465881;9879.259055570861;9654.324197306192;9663.661771837951;9744.02559744027;9745.512724363753;9760.560774765152;40343.79828100841;40527.392089118686;40613.18773624511;40623.59376406228;40797.980050498976;3307.1566750478473;3330.2673411849355;3342.1143396999814;3406.0633325835347;3415.84628691707;26632;27166.864165679242;30386.240343991576;31275;31971.200719981953;3206.7434605231524;3236.385086833494;3251.0246925922365;3273.672632736731;3349.3301339731906;519659;523731;526603.7339626593;526962;530163;55548.44451555433;56170.438295617336;56490.71754641201;56583.71708141433;56681.86636267252;33831.66168338335;34679.653203468144;34704.30591388158;58432.7078364614;58617;16485.258163382678;16684.416045438593;16727.996320220827;16794.496165115066;16815.495535133967;12824.23054616726;12824.909882660078;12944.741105177844;12955.5465558705;12982.610521684364;18462.261509539738;18524.629507409776;18657.346992855186;18683;18714.906425467787;7734.806629834299;7872.33085187754;7908.192591629889;7939.166387529226;7952.880706789403;871.9084496127815;918.3112665500875;965.8551217317458;1017.0439786600572;1037.4760745823312;22535.436614084778;23207.767922320898;24763.62854557183;26666.799994000317;27238;960.3037797596826;970.5680971967548;1041.063043261071;1061.5117046158698;1157.4097210422744;11892.286462812259;15321.157336346385;15442.922785386001;15560.533184004498;15582;698951;701366;701565;703767;705681;54857;55209;55791.88416231653;56019;56169;1307.1764788183384;1337.6321511584197;1354.9796753048515;1359.8776110150134;1363.9522616708368;24410.145644902343;28456.715432845405;29134;30350.392992140474;30539.778408863396;47062.529374707185;53482.4651753493;55409.44590554043;55566.49950752307;57803;594531;601472;602063;602674;606001;32112;41534.79232603939;41654.583454166284;41926;42506;46826.765866171816;46836;47520.7623961878;47661.28508072451;48047.279290809965;38732.03169920774;38746.6125338743;39047.219055619295;40292.79853600713;40315.19369612532;32267.51598726068;37505.81247093747;38142.618573815016;38578.842634721004;39263.41104883486;37123.81438092792;37405.62594374021;37630.247395052495;37797.811010944766;38126.809365954105;33256.667433325354;34097.48853767245;34531.4820277691;34633.826830865684;34843.30313393667;25454.109106181193;25609.743902560735;25879.870600646875;25893.223203303933;25896.834642440757 05:19:18 INFO - PROCESS | 1955 | |2;jquery.html;350.7194244604317;371.2926874304449;380.3477036881748;385.6259428354495;400.8076123460739;19242.203934383637;20164;20679.793202067995;21069.73581585104;21216;19397.321093761722;19705.408837734856;20113.396598102045;20147.697784533244;20246.7851928884;5046.343975283226;5821.854453638661;6043.8186854394435;6044.153818465418;6061.818145455643;5920.940790592082;5929.762809487637;5930.673812940287;5957.374475680034;5976.581639285252;5789.218455508494;5919.763209471616;6110.266767987848;6165.167702360169;6188.566800323979;5786.971065144689;6102.603330783507;6118.571699981003;6146.07808828677;6157.0456579230295;4968.956519130987;5831.854203644901;5973.8805223895715;5984.371640977722;5991.910121348184;10727.249092563523;10913.83629245562;12987.87012129876;13025.86974130256;13071.281079540622;2162.2756376613697;2544.8982040718347;2547.4013606802387;2595.480903819244;2606.8696565171667;8707.346948978851;9033.728988130366;10264.486775661187;10353.79292414155;12046.759064818654;1440.0423718227366;1806.4129158023563;1838.7517685112473;1864.850811935042;1868.0286251149325;111556.4422177892;121824;124267;125435.72821358987;126738.31849968199;11004.779904401867;11120.833187502194;11217.719557010976;11270.661880143609;11427.771444571063;14203.431862725582;14337.56987290383;14363.640908977359;14366;14383.928080359532;8333.708275217352;8452.704155354533;8513.40406171562;8516.361272904556;8645.913540864636;8064.233897779702;8504.744857654281;8525.57372131391;8626.439281446686;8653.913460865437;3352.7653064285264;4473.261911784519;4514.277715565559;4569.725816451024;4625.722456652545;1859.9721004184948;1938.2053358123385;1942.8834269943566;1944.931927382535;2001.9199232030555;801.9558924259105;861.763015170833;880.1418616848522;914.6113648930695;916.276141847946;20139.59720805576;21202.15191392327;21264.36206913795;21415.78584214169;21521.89239053795;883.7569668341129;917.0283889637333;924.9564565831147;945.9621615135318;1010.5604062232952;10714.892851071545;10969.670909872717;10980.670579882617;11010.614628487965;11063.612773552886;502617;552753;580283;614720;616245;93041.06958930459;135142;136090.27819443555;136755.31622341825;144696;947.1617618407759;969.3699095587924;997.0527998401548;1020.4999550220339;1020.7448137965508;19234.80765192386;19243.8075619242;19527.804721953165;19584.31454899072;19640.410787676392;54550.7272463651;57339.56651083756;57387.139192912975;57756;58393.70803145957;75948;77357.61321193358;81550.18449815664;87235;87627.56186219028;7948.761537153895;8066.637001335072;8165.387595930448;8212.712555060543;8376;12317.692057698629;12855;12919.22484650924;13218.867811322149;13318.733625327633;11096.33421994683;11189.944050279697;11343.773124537629;11435.913588209107;11582.884171158517;10947.45262736844;11069.114470842153;11081;11222.88777112251;11457.541698331974;9231.907680923105;9445.858312125461;10215.540300686338;11664.88335116638;11718.121140914294;8037.115917249215;8125.431219814556;8191.959040204761;8205.876911846446;8225.83548329042;8789.34079943994;9013.324000699842;9354.625814967598;10377.452643765237;11435.714107147387 05:19:18 INFO - PROCESS | 1955 | |3;mootools.html;1468.3906178935738;1481.1705444950828;1676.840700133487;1830.6613276543849;1842.8709990300683;7960.164182760799;8215.301699355547;8552.657893684252;9093.13615206556;9121.680741174061;8507.191816777397;8737.825243495126;8791.51646659435;8848.646054157825;8861.069587693297;2369.7985671217966;2393.56915755164;2427.78149966503;2428.732839387666;2435.9147429839873;2385.49904520051;2405.603075492542;2410.554047501209;2434.6591477193206;2442.218490083174;2402.37538240058;2404.759524047599;2421.1767998880377;2428.9028438862424;2440.4143005678598;2391.5336509380677;2391.940201494959;2397.6763136976547;2409.734929157791;2421.8425802322968;2390.6294524348755;2403.2790162951046;2408.385861605297;2411.7105947286263;2415.6376543518436;8921.464712117293;8930.598123084477;8933.330000250007;8952.552372381355;8961.507117108556;4478.350639157336;4572.634189264851;5245.396779370383;5389.595780316492;5423.891522169534;3357.78174418662;3460.567429071366;3538.4161613333767;3550.840212190458;3566.251087271676;1074.2694967422149;1107.6676996900978;1113.7549739057395;1119.0655802405001;1134.4951496584024;11293.265937714023;11496;11534.077273818075;11534.307941523452;11570.94214528922;4544.977275113603;4624.375709279204;4820.903581928412;4834.7824347903725;4834.903301933941;9724.75688107803;9967.40195588267;9984.700458986243;9987.700368988943;9991.250656200815;5362.436944120889;5382.892342153214;5394.083005889008;5397.487238712316;5412.972935135378;4409.316555933835;4579.633629309647;4778.331033655291;4915;4943;3589.94615080774;3699.3711069118312;3969.64273215412;4023.7183397161916;4062.695297852673;1296.8378952630922;1307.9542216022585;1307.9673008174873;1315.7500074985915;1318.0971034840973;766.9501482403626;831.8544254755394;857.6183598298821;888.693400776731;939.2110627073291;5581.525570326487;5949.672767997715;5973.850653733604;6009.639421634716;6123.908141377883;917.5962576466334;937.8077494113691;938.1416004355938;939.1876027236527;969.9563519641492;3943.290207762573;3951.269015232191;3989.7207195496035;4059.350503919418;4065.3495440729935;10346.37921724699;10684.771251306043;10688;10724.892751072546;10731.356118632906;10560.943905609378;10594.205434592282;10606.469676516142;10681.732956676145;10694.251402401756;7832.216778322236;8235.95882020598;8257.917420825836;8358.707445239508;8369.748907532783;5866.2373891393845;5930.762769489172;5938.584299099021;6036.6679832609625;6213.254409470893;10105.141063009432;10111.696649100539;10201.74495637615;10247.333923294962;10271.075603195748;8120;10017;10066.094051535105;10081.798364032826;10205.285630006125;7025.332593403618;7237.819054523679;7492;7580.279873412238;7690.730824421118;8323.542205178774;8519.659213631634;8544.35917306217;8557.871631925402;8563.186497282748;7320.890186647098;7422.814429639302;7494.025776649;7565.356944659766;7640.235976402315;7294;7357.5217610855125;7453.919181718729;7507.061617297838;7574.128975167926;6922.550034247758;7037.085178926707;7044.788656340318;7062.187848397294;7086.39765619928;4964.826231081895;5003.249512573143;5067.594592432522;5109.3613298337705;5208.661437006583;5035.043341765199;5101.260317254051;5140.4088529818055;5149.098907691214;5233 05:19:18 INFO - PROCESS | 1955 | |4;prototype.html;272.5543735991654;308.192535556841;319.8848414570754;320.3049382839239;320.5224215918282;5226.294450249225;5305.469453054692;5393.838184854456;5482.2873026506495;5485.424030476803;4000.359942409215;4130.401091841682;4950.925736113953;5188.740562971855;5401.459854014596;4111.506619205699;4131.173765246947;4132.958670413303;4135.979320103395;4154.127633197032;3761.962380376189;4016.9799151004204;4129.814158362866;4143.8342466301465;4171.895702607444;3828.6554210121085;4061.2283666103344;4079.571644977279;4080.224757296119;4088.425956006944;3894.7273690841653;4066.247744167309;4083.224187404384;4093.1609020151095;4109.773962432065;3101.8449077546256;3289.3585750778607;3305.603327600679;3582.498450216972;3592.209713862946;5121.667091639031;5177.922331165036;5181.171012637959;5185.740712964337;5193.558547523465;3832.2527107214105;4047.797610119512;4239.354155426331;4564.497905230422;4627.1439783640135;4541.069080838421;4713.434387873443;4892.486288939681;5490.258815059956;5643.661380317194;3133.65529791725;3837.175007373428;3838.4434257032854;3872.5933776953298;3874.050857539921;4956.702597844105;5892.911606325909;5904.350521442638;5924.674142922137;5958.106284057383;3587.712982961357;3865.729398942047;3919.2945269851703;3927.4501569780255;3929.351656976567;2998.130542142798;3819.1597848473293;3886.5724770275247;3890.2219556088894;3923.6272554107313;4083.9387409188885;4227.070044590185;4609.032103258286;4632.123989784142;4645.512221216791;5489.39616642169;5737.08206686928;5776.393478684678;5818.650880947157;5836.715922497044;1302.7459645369063;1387.6461502317047;1402.2638114989509;1404.7963045358365;1407.465163237963;780.5199802121767;935.7052528453515;939.5208443693629;966.5795379010153;972.781124247053;3755.7793717041523;3882.5535063467496;4461.836009535373;4496.976360637726;4526.275795872711;4044.5753195914594;4265.53079161292;4553.385292985471;4561.931571026437;4694.4977607166065;4006.6794656427414;4456.217512994828;4528.8487968215495;4529.501754806968;4596.954030459719;2085.562031973272;2241.8454495934607;2447.968186405315;2545.8854351554223;2588.4693637804203;3933.4296527003426;4558.452985641744;4758.476567577563;4926.975365123223;5141.820036298712;4724.055188962221;5072.163093089673;5073.797048118034;5092.134337162691;5099.796008159707;4433.6009759121835;5606.775728970795;6117.265928088658;6131.785387511416;6175.567710260419;4858.902821943612;5789.45000224978;5981.760729570942;5982.970085149546;5999.520038397005;4414.3157810539415;5169.431362550192;5257.316548848626;5898.970505147447;5926.585139040399;4903.83702511885;5019.598432125348;6028.2766068072115;6189.319174890668;6247.625142491465;3738.981305093457;3791.943120853134;3847.672158344273;3921.3725803871243;3947.9340578043193;4250.978745106255;5076.771545280398;5181.792728290826;5849.713063126105;5880.441358070976;4912.778924948458;5166.173412254021;5668.61917994435;5714.885702286014;5736.737917658109;4252.915506545811;5219.608529360389;5326.445888765205;5401.135818269058;5714.171445140349;4814.074370251928;4993.900121997613;5364.034473794754;5486.369067557282;5580.720963951868;5120.129577971828;5418.83743487696;5673.120666296731;5681.318241811009;5731.140328950524;4162.729422587522;4966.932109596454;5307.6284660073425;5453.809116680897;5644.435556444323 05:19:18 INFO - PROCESS | 1955 | |5;yui.html;705.5167210460831;737.7049180327868;773.408342617897;781.1134362498564;813.4834380168592;15065.096094234339;15727.370905163809;15785.763213551778;15809.446669366574;15880.444184453532;14135.222562759043;14252.786208206886;14261.429542818274;14267.1439713617;14272.2863856807;680.5950459476628;707.794739525537;854.6837670062085;857.9528125953086;879.3800370738614;887.3699673232013;888.4003297774009;890.3055616619015;891.01542795211;892.1435421994893;928.4939707859199;950.0546955779009;950.5437390052779;950.5959967014026;952;934.5374039850318;941.580996456577;943.1888575824785;946.0870260198873;949.126803340928;701.4353445476389;708.2952462299993;708.3553965891015;710.9146902371697;712.5226098514008;14667.413303467849;14690.55928322152;14705.264736763225;14749.483768068174;14758.631034224123;14365.066270692372;14455.927720361437;14496.710065798625;14504.129752214796;14525.055871368433;11836.940815295955;11874;11907.16649834512;11925.761484770255;11969.581064662782;3751.835462636471;3774.6602805747616;3860.884173474772;3868.18768058708;3996.4205190247253;128431;130073;130087;133078;134093.3295333527;9110.772230694285;9366.90633093674;11094.464753001486;11123.499442525106;11130.721731956766;17283.91358043227;22883.42791430194;23070.077196912272;23102.075916963135;23250.069997199924;7762.06855177371;7872.741170769252;7892.486988345854;7900.525968441912;8070.515769053875;4240.363945408213;4317.791018514821;4341.261985462479;4365.80353884076;4498.640108791289;3273.852676629557;3291.489819078047;3584.8566057357934;3784.829682664287;3971.5631280559114;700.7232143303366;710.6304721544768;724.4132252875188;727.6034561164179;730.444861904956;547.622140722908;631.2740348599148;639.6833567384175;660.5871330418488;663.171036204744;9416.95291523538;9501;9742.366746161619;9783.706488805346;9802.754931126636;480.5218807286772;549.3078720811806;626.8526896179485;646.1019183335168;704.1620471638734;4335.263005289108;4662.743549104764;4717.962048349357;4770.521138447023;4772.331873537708;71143;110689.44655276672;154293;155156.44843551645;168659.15670421568;77212.61393692995;77416.61291693618;134800;141360;145309;106587;124166;125419;127393;129563.35218324036;9416.868572793399;9936.950315248378;9948.427384360617;9992;10246.692599221737;59422;61287.77424451575;61328.38671613227;62611.37388626238;64058.67970660117;97071;97654.60980145072;98114;100514;103072.45391319286;13082.934585327013;13163.736725265633;13210.933945330597;13307.06850520493;13332.733345333232;14405.639859003608;14615.634609134857;14940.402383904522;15343.61640958985;15645.217739112772;13676.794848077487;13820.723585528032;14136.646583835487;14369.497067602584;14577.708445830813;13663.142482931238;13827.446902123802;13861.376238069513;14281.928590356982;14377.109917672879;13188.868111319147;13705.588832335046;13724.245166515937;13758.65603359924;13798.586042418743;11139.72150696239;11151;11293.445421650467;11732.765344692887;11892.345920974429;9411.623535058521;11032;11408.315501069963;11467;11655.941720291345 05:19:18 INFO - PROCESS | 1955 | __end_tp_report 05:19:18 INFO - PROCESS | 1955 | __start_cc_report 05:19:18 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,392 05:19:18 INFO - PROCESS | 1955 | __end_cc_report 05:19:18 INFO - PROCESS | 1955 | __startTimestamp1473423558613__endTimestamp 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | ------- Summary: start ------- 05:19:18 INFO - PROCESS | 1955 | Number of tests: 6 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5784.52 Median:6055.27 stddev:677.83 (11.2%) stddev-sans-first:639.58 05:19:18 INFO - PROCESS | 1955 | Values: 2848.4 3293.9 3396.8 3400.0 3478.5 5705.5 5746.1 5751.8 5824.6 5859.6 5756.4 5845.2 5881.1 5891.6 6021.3 5239.1 5358.9 5384.3 5421.6 5430.7 5190.7 5344.3 5403.8 5410.4 5434.3 5198.4 5244.4 5266.6 5280.3 5305.2 5222.0 5271.7 5294.5 5320.7 5336.1 5284.1 5307.1 5334.8 5337.2 5352.2 5499.9 5758.7 5821.1 5902.3 5910.8 6231.9 6267.9 6281.9 6320.1 6338.2 5302.5 6178.3 6232.2 6261.7 6309.5 4819.8 5939.6 5995.7 6036.7 6091.7 6369.3 6429.7 6440.6 6441.3 6452.1 6111.3 6242.9 6247.0 6260.2 6292.6 6161.3 6223.3 6255.2 6268.8 6294.5 6150.7 6264.4 6284.1 6285.7 6290.2 6187.2 6284.4 6307.2 6329.1 6342.0 4225.5 4885.0 4958.6 4965.5 5056.3 4455.0 4509.1 4541.0 4562.2 4588.0 5952.3 6056.3 6064.6 6069.2 6108.2 6132.9 6221.6 6230.7 6233.6 6292.3 5970.8 6031.3 6033.3 6062.2 6080.5 5523.3 5525.4 5527.7 5537.4 5550.1 6277.8 6327.8 6410.0 6514.6 6522.9 5469.9 6287.0 6290.2 6306.0 6326.6 6505.7 6509.4 6515.5 6530.8 6550.9 4975.6 6200.7 6212.0 6251.7 6281.9 4775.6 6207.6 6219.8 6301.2 6307.7 6055.3 6208.1 6235.8 6255.4 6264.2 5125.9 5210.6 5275.2 5797.4 6013.9 5842.9 5971.8 6060.8 6107.1 6135.8 6117.0 6179.1 6192.9 6207.8 6227.2 5285.3 5964.2 5987.8 6136.8 6168.5 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:73224.37 Median:40710.79 stddev:164441.71 (403.9%) stddev-sans-first:164812.26 05:19:18 INFO - PROCESS | 1955 | Values: 457.4 478.7 498.0 525.5 533.2 38002.4 39844.4 39914.4 41913.6 41957.8 40961.2 41152.0 41460.8 41633.8 41711.8 9654.5 9806.7 9831.7 9840.8 9860.5 9758.5 9874.7 9888.1 9895.0 9901.0 9854.1 9856.8 9898.0 9903.1 9916.0 9833.1 9842.2 9846.0 9863.5 9879.3 9654.3 9663.7 9744.0 9745.5 9760.6 40343.8 40527.4 40613.2 40623.6 40798.0 3307.2 3330.3 3342.1 3406.1 3415.8 26632.0 27166.9 30386.2 31275.0 31971.2 3206.7 3236.4 3251.0 3273.7 3349.3 519659.0 523731.0 526603.7 526962.0 530163.0 55548.4 56170.4 56490.7 56583.7 56681.9 33831.7 34679.7 34704.3 58432.7 58617.0 16485.3 16684.4 16728.0 16794.5 16815.5 12824.2 12824.9 12944.7 12955.5 12982.6 18462.3 18524.6 18657.3 18683.0 18714.9 7734.8 7872.3 7908.2 7939.2 7952.9 871.9 918.3 965.9 1017.0 1037.5 22535.4 23207.8 24763.6 26666.8 27238.0 960.3 970.6 1041.1 1061.5 1157.4 11892.3 15321.2 15442.9 15560.5 15582.0 698951.0 701366.0 701565.0 703767.0 705681.0 54857.0 55209.0 55791.9 56019.0 56169.0 1307.2 1337.6 1355.0 1359.9 1364.0 24410.1 28456.7 29134.0 30350.4 30539.8 47062.5 53482.5 55409.4 55566.5 57803.0 594531.0 601472.0 602063.0 602674.0 606001.0 32112.0 41534.8 41654.6 41926.0 42506.0 46826.8 46836.0 47520.8 47661.3 48047.3 38732.0 38746.6 39047.2 40292.8 40315.2 32267.5 37505.8 38142.6 38578.8 39263.4 37123.8 37405.6 37630.2 37797.8 38126.8 33256.7 34097.5 34531.5 34633.8 34843.3 25454.1 25609.7 25879.9 25893.2 25896.8 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:34314.99 Median:1851.74 stddev:96516.47 (5212.2%) stddev-sans-first:96753.53 05:19:18 INFO - PROCESS | 1955 | Values: 350.7 371.3 380.3 385.6 400.8 19242.2 20164.0 20679.8 21069.7 21216.0 19397.3 19705.4 20113.4 20147.7 20246.8 5046.3 5821.9 6043.8 6044.2 6061.8 5920.9 5929.8 5930.7 5957.4 5976.6 5789.2 5919.8 6110.3 6165.2 6188.6 5787.0 6102.6 6118.6 6146.1 6157.0 4969.0 5831.9 5973.9 5984.4 5991.9 10727.2 10913.8 12987.9 13025.9 13071.3 2162.3 2544.9 2547.4 2595.5 2606.9 8707.3 9033.7 10264.5 10353.8 12046.8 1440.0 1806.4 1838.8 1864.9 1868.0 111556.4 121824.0 124267.0 125435.7 126738.3 11004.8 11120.8 11217.7 11270.7 11427.8 14203.4 14337.6 14363.6 14366.0 14383.9 8333.7 8452.7 8513.4 8516.4 8645.9 8064.2 8504.7 8525.6 8626.4 8653.9 3352.8 4473.3 4514.3 4569.7 4625.7 1860.0 1938.2 1942.9 1944.9 2001.9 802.0 861.8 880.1 914.6 916.3 20139.6 21202.2 21264.4 21415.8 21521.9 883.8 917.0 925.0 946.0 1010.6 10714.9 10969.7 10980.7 11010.6 11063.6 502617.0 552753.0 580283.0 614720.0 616245.0 93041.1 135142.0 136090.3 136755.3 144696.0 947.2 969.4 997.1 1020.5 1020.7 19234.8 19243.8 19527.8 19584.3 19640.4 54550.7 57339.6 57387.1 57756.0 58393.7 75948.0 77357.6 81550.2 87235.0 87627.6 7948.8 8066.6 8165.4 8212.7 8376.0 12317.7 12855.0 12919.2 13218.9 13318.7 11096.3 11189.9 11343.8 11435.9 11582.9 10947.5 11069.1 11081.0 11222.9 11457.5 9231.9 9445.9 10215.5 11664.9 11718.1 8037.1 8125.4 8192.0 8205.9 8225.8 8789.3 9013.3 9354.6 10377.5 11435.7 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5728.07 Median:4953.91 stddev:3179.73 (64.2%) stddev-sans-first:3172.53 05:19:18 INFO - PROCESS | 1955 | Values: 1468.4 1481.2 1676.8 1830.7 1842.9 7960.2 8215.3 8552.7 9093.1 9121.7 8507.2 8737.8 8791.5 8848.6 8861.1 2369.8 2393.6 2427.8 2428.7 2435.9 2385.5 2405.6 2410.6 2434.7 2442.2 2402.4 2404.8 2421.2 2428.9 2440.4 2391.5 2391.9 2397.7 2409.7 2421.8 2390.6 2403.3 2408.4 2411.7 2415.6 8921.5 8930.6 8933.3 8952.6 8961.5 4478.4 4572.6 5245.4 5389.6 5423.9 3357.8 3460.6 3538.4 3550.8 3566.3 1074.3 1107.7 1113.8 1119.1 1134.5 11293.3 11496.0 11534.1 11534.3 11570.9 4545.0 4624.4 4820.9 4834.8 4834.9 9724.8 9967.4 9984.7 9987.7 9991.3 5362.4 5382.9 5394.1 5397.5 5413.0 4409.3 4579.6 4778.3 4915.0 4943.0 3589.9 3699.4 3969.6 4023.7 4062.7 1296.8 1308.0 1308.0 1315.8 1318.1 767.0 831.9 857.6 888.7 939.2 5581.5 5949.7 5973.9 6009.6 6123.9 917.6 937.8 938.1 939.2 970.0 3943.3 3951.3 3989.7 4059.4 4065.3 10346.4 10684.8 10688.0 10724.9 10731.4 10560.9 10594.2 10606.5 10681.7 10694.3 7832.2 8236.0 8257.9 8358.7 8369.7 5866.2 5930.8 5938.6 6036.7 6213.3 10105.1 10111.7 10201.7 10247.3 10271.1 8120.0 10017.0 10066.1 10081.8 10205.3 7025.3 7237.8 7492.0 7580.3 7690.7 8323.5 8519.7 8544.4 8557.9 8563.2 7320.9 7422.8 7494.0 7565.4 7640.2 7294.0 7357.5 7453.9 7507.1 7574.1 6922.6 7037.1 7044.8 7062.2 7086.4 4964.8 5003.2 5067.6 5109.4 5208.7 5035.0 5101.3 5140.4 5149.1 5233.0 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4365.48 Median:4618.09 stddev:1358.17 (29.4%) stddev-sans-first:1326.78 05:19:18 INFO - PROCESS | 1955 | Values: 272.6 308.2 319.9 320.3 320.5 5226.3 5305.5 5393.8 5482.3 5485.4 4000.4 4130.4 4950.9 5188.7 5401.5 4111.5 4131.2 4133.0 4136.0 4154.1 3762.0 4017.0 4129.8 4143.8 4171.9 3828.7 4061.2 4079.6 4080.2 4088.4 3894.7 4066.2 4083.2 4093.2 4109.8 3101.8 3289.4 3305.6 3582.5 3592.2 5121.7 5177.9 5181.2 5185.7 5193.6 3832.3 4047.8 4239.4 4564.5 4627.1 4541.1 4713.4 4892.5 5490.3 5643.7 3133.7 3837.2 3838.4 3872.6 3874.1 4956.7 5892.9 5904.4 5924.7 5958.1 3587.7 3865.7 3919.3 3927.5 3929.4 2998.1 3819.2 3886.6 3890.2 3923.6 4083.9 4227.1 4609.0 4632.1 4645.5 5489.4 5737.1 5776.4 5818.7 5836.7 1302.7 1387.6 1402.3 1404.8 1407.5 780.5 935.7 939.5 966.6 972.8 3755.8 3882.6 4461.8 4497.0 4526.3 4044.6 4265.5 4553.4 4561.9 4694.5 4006.7 4456.2 4528.8 4529.5 4597.0 2085.6 2241.8 2448.0 2545.9 2588.5 3933.4 4558.5 4758.5 4927.0 5141.8 4724.1 5072.2 5073.8 5092.1 5099.8 4433.6 5606.8 6117.3 6131.8 6175.6 4858.9 5789.5 5981.8 5983.0 5999.5 4414.3 5169.4 5257.3 5899.0 5926.6 4903.8 5019.6 6028.3 6189.3 6247.6 3739.0 3791.9 3847.7 3921.4 3947.9 4251.0 5076.8 5181.8 5849.7 5880.4 4912.8 5166.2 5668.6 5714.9 5736.7 4252.9 5219.6 5326.4 5401.1 5714.2 4814.1 4993.9 5364.0 5486.4 5580.7 5120.1 5418.8 5673.1 5681.3 5731.1 4162.7 4966.9 5307.6 5453.8 5644.4 05:19:18 INFO - PROCESS | 1955 | 05:19:18 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:25237.82 Median:3763.25 stddev:40664.99 (1080.6%) stddev-sans-first:40737.35 05:19:18 INFO - PROCESS | 1955 | Values: 705.5 737.7 773.4 781.1 813.5 15065.1 15727.4 15785.8 15809.4 15880.4 14135.2 14252.8 14261.4 14267.1 14272.3 680.6 707.8 854.7 858.0 879.4 887.4 888.4 890.3 891.0 892.1 928.5 950.1 950.5 950.6 952.0 934.5 941.6 943.2 946.1 949.1 701.4 708.3 708.4 710.9 712.5 14667.4 14690.6 14705.3 14749.5 14758.6 14365.1 14455.9 14496.7 14504.1 14525.1 11836.9 11874.0 11907.2 11925.8 11969.6 3751.8 3774.7 3860.9 3868.2 3996.4 128431.0 130073.0 130087.0 133078.0 134093.3 9110.8 9366.9 11094.5 11123.5 11130.7 17283.9 22883.4 23070.1 23102.1 23250.1 7762.1 7872.7 7892.5 7900.5 8070.5 4240.4 4317.8 4341.3 4365.8 4498.6 3273.9 3291.5 3584.9 3784.8 3971.6 700.7 710.6 724.4 727.6 730.4 547.6 631.3 639.7 660.6 663.2 9417.0 9501.0 9742.4 9783.7 9802.8 480.5 549.3 626.9 646.1 704.2 4335.3 4662.7 4718.0 4770.5 4772.3 71143.0 110689.4 154293.0 155156.4 168659.2 77212.6 77416.6 134800.0 141360.0 145309.0 106587.0 124166.0 125419.0 127393.0 129563.4 9416.9 9937.0 9948.4 9992.0 10246.7 59422.0 61287.8 61328.4 62611.4 64058.7 97071.0 97654.6 98114.0 100514.0 103072.5 13082.9 13163.7 13210.9 13307.1 13332.7 14405.6 14615.6 14940.4 15343.6 15645.2 13676.8 13820.7 14136.6 14369.5 14577.7 13663.1 13827.4 13861.4 14281.9 14377.1 13188.9 13705.6 13724.2 13758.7 13798.6 11139.7 11151.0 11293.4 11732.8 11892.3 9411.6 11032.0 11408.3 11467.0 11655.9 05:19:18 INFO - PROCESS | 1955 | -------- Summary: end -------- 05:19:18 INFO - PROCESS | 1955 | 05:19:19 INFO - TEST-INFO | 1955: exit 0 05:19:19 INFO - TEST-OK | dromaeo_css | took 1114515ms 05:19:19 INFO - TEST-START | kraken 05:19:19 INFO - operating with platform_type : mac_ 05:19:19 INFO - Initialising browser for kraken test... 05:19:19 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpmOD_Qi/profile http://localhost:56063/getInfo.html) 05:19:22 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 05:19:22 INFO - PROCESS | 1959 | colorDepth:24 05:19:22 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 05:19:22 INFO - PROCESS | 1959 | __metrics 05:19:22 ERROR - PROCESS | 1959 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 05:19:22 INFO - TEST-INFO | 1959: exit 0 05:19:22 INFO - Browser initialized. 05:19:22 INFO - Running cycle 1/1 for kraken test... 05:19:22 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpmOD_Qi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:19:34 INFO - PROCESS | 1961 | RSS: Main: 244256768 05:19:34 INFO - PROCESS | 1961 | 05:19:37 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56063/tests/kraken/driver.html?testName=audio-beat-detection) 05:19:38 INFO - PROCESS | 1961 | RSS: Main: 257015808 05:19:38 INFO - PROCESS | 1961 | 05:19:41 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56063/tests/kraken/driver.html?testName=audio-dft) 05:19:42 INFO - PROCESS | 1961 | RSS: Main: 265605120 05:19:42 INFO - PROCESS | 1961 | 05:19:45 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56063/tests/kraken/driver.html?testName=audio-fft) 05:19:45 INFO - PROCESS | 1961 | RSS: Main: 263254016 05:19:45 INFO - PROCESS | 1961 | 05:19:48 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56063/tests/kraken/driver.html?testName=audio-oscillator) 05:19:48 INFO - PROCESS | 1961 | RSS: Main: 265723904 05:19:48 INFO - PROCESS | 1961 | 05:19:50 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56063/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:19:51 INFO - PROCESS | 1961 | RSS: Main: 266100736 05:19:51 INFO - PROCESS | 1961 | 05:19:54 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56063/tests/kraken/driver.html?testName=imaging-darkroom) 05:19:54 INFO - PROCESS | 1961 | RSS: Main: 266256384 05:19:54 INFO - PROCESS | 1961 | 05:19:57 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56063/tests/kraken/driver.html?testName=imaging-desaturate) 05:19:58 INFO - PROCESS | 1961 | RSS: Main: 266571776 05:19:58 INFO - PROCESS | 1961 | 05:20:01 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56063/tests/kraken/driver.html?testName=json-parse-financial) 05:20:02 INFO - PROCESS | 1961 | RSS: Main: 267198464 05:20:02 INFO - PROCESS | 1961 | 05:20:03 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56063/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:20:04 INFO - PROCESS | 1961 | RSS: Main: 266711040 05:20:04 INFO - PROCESS | 1961 | 05:20:06 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:20:07 INFO - PROCESS | 1961 | RSS: Main: 266862592 05:20:07 INFO - PROCESS | 1961 | 05:20:09 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:20:10 INFO - PROCESS | 1961 | RSS: Main: 267190272 05:20:10 INFO - PROCESS | 1961 | 05:20:12 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:20:13 INFO - PROCESS | 1961 | RSS: Main: 267616256 05:20:13 INFO - PROCESS | 1961 | 05:20:16 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:20:17 INFO - PROCESS | 1961 | RSS: Main: 267657216 05:20:17 INFO - PROCESS | 1961 | 05:20:18 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:56063/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56063/tests/kraken/driver.html?testName=ai-astar) 05:20:19 INFO - PROCESS | 1961 | RSS: Main: 269430784 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | __start_tp_report 05:20:19 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 05:20:19 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 05:20:19 INFO - PROCESS | 1961 | |i|pagename|runs| 05:20:19 INFO - PROCESS | 1961 | |0;ai-astar;105;114;115;112;105;111;122;117;115;133 05:20:19 INFO - PROCESS | 1961 | |1;audio-beat-detection;147;113;131;161;118;147;156;142;154;142 05:20:19 INFO - PROCESS | 1961 | |2;audio-dft;201;210;217;197;197;199;200;196;201;195 05:20:19 INFO - PROCESS | 1961 | |3;audio-fft;70;87;78;165;53;76;83;85;122;53 05:20:19 INFO - PROCESS | 1961 | |4;audio-oscillator;69;74;133;74;101;105;123;94;58;112 05:20:19 INFO - PROCESS | 1961 | |5;imaging-gaussian-blur;92;87;89;87;102;131;89;87;91;87 05:20:19 INFO - PROCESS | 1961 | |6;imaging-darkroom;95;98;102;152;123;95;96;95;95;97 05:20:19 INFO - PROCESS | 1961 | |7;imaging-desaturate;91;117;151;150;92;90;133;145;141;97 05:20:19 INFO - PROCESS | 1961 | |8;json-parse-financial;56;57;60;58;60;55;57;57;56;56 05:20:19 INFO - PROCESS | 1961 | |9;json-stringify-tinderbox;68;67;74;74;90;96;74;72;74;109 05:20:19 INFO - PROCESS | 1961 | |10;stanford-crypto-aes;87;86;88;86;85;87;106;107;86;87 05:20:19 INFO - PROCESS | 1961 | |11;stanford-crypto-ccm;140;137;139;138;139;138;140;149;189;163 05:20:19 INFO - PROCESS | 1961 | |12;stanford-crypto-pbkdf2;156;231;245;202;171;171;172;166;167;170 05:20:19 INFO - PROCESS | 1961 | |13;stanford-crypto-sha256-iterative;69;67;69;69;68;66;82;91;92;76 05:20:19 INFO - PROCESS | 1961 | __end_tp_report 05:20:19 INFO - PROCESS | 1961 | __start_cc_report 05:20:19 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,963 05:20:19 INFO - PROCESS | 1961 | __end_cc_report 05:20:19 INFO - PROCESS | 1961 | __startTimestamp1473423619199__endTimestamp 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | ------- Summary: start ------- 05:20:19 INFO - PROCESS | 1961 | Number of tests: 14 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:8.18 (7.1%) stddev-sans-first:7.86 05:20:19 INFO - PROCESS | 1961 | Values: 105.0 114.0 115.0 112.0 105.0 111.0 122.0 117.0 115.0 133.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:147.00 stddev:15.92 (10.8%) stddev-sans-first:16.74 05:20:19 INFO - PROCESS | 1961 | Values: 147.0 113.0 131.0 161.0 118.0 147.0 156.0 142.0 154.0 142.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#2] audio-dft Cycles:10 Average:201.30 Median:200.50 stddev:6.95 (3.5%) stddev-sans-first:7.37 05:20:19 INFO - PROCESS | 1961 | Values: 201.0 210.0 217.0 197.0 197.0 199.0 200.0 196.0 201.0 195.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#3] audio-fft Cycles:10 Average:87.20 Median:77.00 stddev:33.55 (43.6%) stddev-sans-first:35.01 05:20:19 INFO - PROCESS | 1961 | Values: 70.0 87.0 78.0 165.0 53.0 76.0 83.0 85.0 122.0 53.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:63.50 stddev:24.89 (39.2%) stddev-sans-first:24.66 05:20:19 INFO - PROCESS | 1961 | Values: 69.0 74.0 133.0 74.0 101.0 105.0 123.0 94.0 58.0 112.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:88.00 stddev:13.71 (15.6%) stddev-sans-first:14.52 05:20:19 INFO - PROCESS | 1961 | Values: 92.0 87.0 89.0 87.0 102.0 131.0 89.0 87.0 91.0 87.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:95.00 stddev:18.67 (19.6%) stddev-sans-first:19.46 05:20:19 INFO - PROCESS | 1961 | Values: 95.0 98.0 102.0 152.0 123.0 95.0 96.0 95.0 95.0 97.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#7] imaging-desaturate Cycles:10 Average:120.70 Median:120.50 stddev:26.15 (21.7%) stddev-sans-first:25.43 05:20:19 INFO - PROCESS | 1961 | Values: 91.0 117.0 151.0 150.0 92.0 90.0 133.0 145.0 141.0 97.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:1.69 (3.0%) stddev-sans-first:1.73 05:20:19 INFO - PROCESS | 1961 | Values: 56.0 57.0 60.0 58.0 60.0 55.0 57.0 57.0 56.0 56.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#9] json-stringify-tinderbox Cycles:10 Average:79.80 Median:74.00 stddev:13.81 (18.7%) stddev-sans-first:13.98 05:20:19 INFO - PROCESS | 1961 | Values: 68.0 67.0 74.0 74.0 90.0 96.0 74.0 72.0 74.0 109.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:86.50 stddev:8.48 (9.8%) stddev-sans-first:8.89 05:20:19 INFO - PROCESS | 1961 | Values: 87.0 86.0 88.0 86.0 85.0 87.0 106.0 107.0 86.0 87.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:140.00 stddev:16.71 (11.9%) stddev-sans-first:17.51 05:20:19 INFO - PROCESS | 1961 | Values: 140.0 137.0 139.0 138.0 139.0 138.0 140.0 149.0 189.0 163.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.10 Median:171.50 stddev:30.40 (17.7%) stddev-sans-first:30.36 05:20:19 INFO - PROCESS | 1961 | Values: 156.0 231.0 245.0 202.0 171.0 171.0 172.0 166.0 167.0 170.0 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - PROCESS | 1961 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:72.50 stddev:9.98 (13.8%) stddev-sans-first:10.36 05:20:19 INFO - PROCESS | 1961 | Values: 69.0 67.0 69.0 69.0 68.0 66.0 82.0 91.0 92.0 76.0 05:20:19 INFO - PROCESS | 1961 | -------- Summary: end -------- 05:20:19 INFO - PROCESS | 1961 | 05:20:19 INFO - TEST-INFO | 1961: exit 0 05:20:20 INFO - TEST-OK | kraken | took 60724ms 05:20:20 INFO - SUITE-END | took 1175s 05:20:20 INFO - Completed test suite (00:19:35) 05:20:20 INFO - javascript benchmark 05:20:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7338.170147329266, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2848.416074704685, 3293.901182964511, 3396.7622266441344, 3399.966000339996, 3478.513008178854, 5705.48650621444, 5746.138079288109, 5751.7986870459545, 5824.621399609023, 5859.648421094732, 5756.395578464264, 5845.181674565553, 5881.059030555112, 5891.587588868781, 6021.277446706401, 5239.1093514102595, 5358.91961620576, 5384.300040994684, 5421.620486565942, 5430.728463576826, 5190.688558686472, 5344.331958505187, 5403.78384864605, 5410.377806552237, 5434.3207099112615, 5198.428172900959, 5244.370675518924, 5266.552343050846, 5280.313559237313, 5305.1776974569, 5221.973890130525, 5271.657342272779, 5294.470552944713, 5320.65415747979, 5336.146216605363, 5284.07528682479, 5307.097793375136, 5334.759935802898, 5337.172738225581, 5352.197170424429, 5499.862503437406, 5758.654480731169, 5821.126830975345, 5902.321233058167, 5910.7931222407005, 6231.937680623227, 6267.905981410283, 6281.905771413432, 6320.083587879778, 6338.239411270677, 5302.549283310923, 6178.289496707891, 6232.1898153240245, 6261.686915654199, 6309.526785491106, 4819.831305904277, 5939.613925094855, 5995.730192141407, 6036.6679832609625, 6091.66495842724, 6369.331220221903, 6429.710663020175, 6440.645764482904, 6441.291457939623, 6452.1289625900845, 6111.297200821962, 6242.906356404657, 6246.968765156145, 6260.24877014752, 6292.559520833498, 6161.291451483047, 6223.346548612421, 6255.155554000243, 6268.811935641938, 6294.464970477561, 6150.661713605795, 6264.373562643699, 6284.12022316876, 6285.654289014148, 6290.182276304051, 6187.195664563668, 6284.371562843679, 6307.24313082433, 6329.082283068929, 6342.048692696132, 4225.471816022997, 4885.022995400934, 4958.553730164303, 4965.528274813887, 5056.317397151411, 4454.977725111418, 4509.075639493896, 4540.954590454119, 4562.224421848294, 4587.977060114678, 5952.345242023374, 6056.273247210275, 6064.636121832704, 6069.15031895535, 6108.236470441195, 6132.906245166601, 6221.595596286227, 6230.657313847782, 6233.625982441068, 6292.276388215322, 5970.761169553256, 6031.336552979169, 6033.336333003366, 6062.181605483292, 6080.48315893154, 5523.337199536081, 5525.4474552544425, 5527.668339899538, 5537.390887002426, 5550.111982082847, 6277.780277690259, 6327.778527751507, 6409.9679501602195, 6514.609123452608, 6522.902156467657, 5469.863253418696, 6287.0, 6290.182276304051, 6306.0225665022, 6326.557141000086, 6505.73977040932, 6509.381608747161, 6515.54391192612, 6530.771423000172, 6550.901736474052, 4975.5522003019905, 6200.658963756856, 6212.0371342441995, 6251.6874156292915, 6281.937180628318, 4775.594074503567, 6207.565470417027, 6219.751209951733, 6301.180846489927, 6307.716152773228, 6055.273367195965, 6208.099825525183, 6235.812925612239, 6255.437010669061, 6264.185655864891, 5125.897482050413, 5210.583153347648, 5275.208718692227, 5797.391273916178, 6013.939860601338, 5842.912356314744, 5971.7611295547695, 6060.818175454743, 6107.144999700135, 6135.753292002278, 6116.969415152896, 6179.104029915727, 6192.907106393498, 6207.844803879939, 6227.19046523949, 5285.339332583427, 5964.165016897551, 5987.820365389045, 6136.815895523141, 6168.537359698131], "value": 5741.531541066386, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.3574128349668, 478.6888522460398, 497.98008079676816, 525.5270256768908, 533.1789044870899, 38002.42996355054, 39844.40233396494, 39914.40128398076, 41913.580864191346, 41957.7902110489, 40961.180776384455, 41152.0, 41460.79269603648, 41633.75098747035, 41711.791441042755, 9654.469004204766, 9806.65676701312, 9831.705048848546, 9840.753981150492, 9860.457674827881, 9758.463284519348, 9874.654387096452, 9888.11007009369, 9894.9505252474, 9901.0, 9854.113129818315, 9856.753581160458, 9898.010198980117, 9903.10872021518, 9916.008399160099, 9833.065858743445, 9842.212622990144, 9845.950770246174, 9863.50682465881, 9879.259055570861, 9654.324197306192, 9663.661771837951, 9744.02559744027, 9745.512724363753, 9760.560774765152, 40343.79828100841, 40527.392089118686, 40613.18773624511, 40623.59376406228, 40797.980050498976, 3307.1566750478473, 3330.2673411849355, 3342.1143396999814, 3406.0633325835347, 3415.84628691707, 26632.0, 27166.864165679242, 30386.240343991576, 31275.0, 31971.200719981953, 3206.7434605231524, 3236.385086833494, 3251.0246925922365, 3273.672632736731, 3349.3301339731906, 519659.0, 523731.0, 526603.7339626593, 526962.0, 530163.0, 55548.44451555433, 56170.438295617336, 56490.71754641201, 56583.71708141433, 56681.86636267252, 33831.66168338335, 34679.653203468144, 34704.30591388158, 58432.7078364614, 58617.0, 16485.258163382678, 16684.416045438593, 16727.996320220827, 16794.496165115066, 16815.495535133967, 12824.23054616726, 12824.909882660078, 12944.741105177844, 12955.5465558705, 12982.610521684364, 18462.261509539738, 18524.629507409776, 18657.346992855186, 18683.0, 18714.906425467787, 7734.806629834299, 7872.33085187754, 7908.192591629889, 7939.166387529226, 7952.880706789403, 871.9084496127815, 918.3112665500875, 965.8551217317458, 1017.0439786600572, 1037.4760745823312, 22535.436614084778, 23207.767922320898, 24763.62854557183, 26666.799994000317, 27238.0, 960.3037797596826, 970.5680971967548, 1041.063043261071, 1061.5117046158698, 1157.4097210422744, 11892.286462812259, 15321.157336346385, 15442.922785386001, 15560.533184004498, 15582.0, 698951.0, 701366.0, 701565.0, 703767.0, 705681.0, 54857.0, 55209.0, 55791.88416231653, 56019.0, 56169.0, 1307.1764788183384, 1337.6321511584197, 1354.9796753048515, 1359.8776110150134, 1363.9522616708368, 24410.145644902343, 28456.715432845405, 29134.0, 30350.392992140474, 30539.778408863396, 47062.529374707185, 53482.4651753493, 55409.44590554043, 55566.49950752307, 57803.0, 594531.0, 601472.0, 602063.0, 602674.0, 606001.0, 32112.0, 41534.79232603939, 41654.583454166284, 41926.0, 42506.0, 46826.765866171816, 46836.0, 47520.7623961878, 47661.28508072451, 48047.279290809965, 38732.03169920774, 38746.6125338743, 39047.219055619295, 40292.79853600713, 40315.19369612532, 32267.51598726068, 37505.81247093747, 38142.618573815016, 38578.842634721004, 39263.41104883486, 37123.81438092792, 37405.62594374021, 37630.247395052495, 37797.811010944766, 38126.809365954105, 33256.667433325354, 34097.48853767245, 34531.4820277691, 34633.826830865684, 34843.30313393667, 25454.109106181193, 25609.743902560735, 25879.870600646875, 25893.223203303933, 25896.834642440757], "value": 19992.64753653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7194244604317, 371.2926874304449, 380.3477036881748, 385.6259428354495, 400.8076123460739, 19242.203934383637, 20164.0, 20679.793202067995, 21069.73581585104, 21216.0, 19397.321093761722, 19705.408837734856, 20113.396598102045, 20147.697784533244, 20246.7851928884, 5046.343975283226, 5821.854453638661, 6043.8186854394435, 6044.153818465418, 6061.818145455643, 5920.940790592082, 5929.762809487637, 5930.673812940287, 5957.374475680034, 5976.581639285252, 5789.218455508494, 5919.763209471616, 6110.266767987848, 6165.167702360169, 6188.566800323979, 5786.971065144689, 6102.603330783507, 6118.571699981003, 6146.07808828677, 6157.0456579230295, 4968.956519130987, 5831.854203644901, 5973.8805223895715, 5984.371640977722, 5991.910121348184, 10727.249092563523, 10913.83629245562, 12987.87012129876, 13025.86974130256, 13071.281079540622, 2162.2756376613697, 2544.8982040718347, 2547.4013606802387, 2595.480903819244, 2606.8696565171667, 8707.346948978851, 9033.728988130366, 10264.486775661187, 10353.79292414155, 12046.759064818654, 1440.0423718227366, 1806.4129158023563, 1838.7517685112473, 1864.850811935042, 1868.0286251149325, 111556.4422177892, 121824.0, 124267.0, 125435.72821358987, 126738.31849968199, 11004.779904401867, 11120.833187502194, 11217.719557010976, 11270.661880143609, 11427.771444571063, 14203.431862725582, 14337.56987290383, 14363.640908977359, 14366.0, 14383.928080359532, 8333.708275217352, 8452.704155354533, 8513.40406171562, 8516.361272904556, 8645.913540864636, 8064.233897779702, 8504.744857654281, 8525.57372131391, 8626.439281446686, 8653.913460865437, 3352.7653064285264, 4473.261911784519, 4514.277715565559, 4569.725816451024, 4625.722456652545, 1859.9721004184948, 1938.2053358123385, 1942.8834269943566, 1944.931927382535, 2001.9199232030555, 801.9558924259105, 861.763015170833, 880.1418616848522, 914.6113648930695, 916.276141847946, 20139.59720805576, 21202.15191392327, 21264.36206913795, 21415.78584214169, 21521.89239053795, 883.7569668341129, 917.0283889637333, 924.9564565831147, 945.9621615135318, 1010.5604062232952, 10714.892851071545, 10969.670909872717, 10980.670579882617, 11010.614628487965, 11063.612773552886, 502617.0, 552753.0, 580283.0, 614720.0, 616245.0, 93041.06958930459, 135142.0, 136090.27819443555, 136755.31622341825, 144696.0, 947.1617618407759, 969.3699095587924, 997.0527998401548, 1020.4999550220339, 1020.7448137965508, 19234.80765192386, 19243.8075619242, 19527.804721953165, 19584.31454899072, 19640.410787676392, 54550.7272463651, 57339.56651083756, 57387.139192912975, 57756.0, 58393.70803145957, 75948.0, 77357.61321193358, 81550.18449815664, 87235.0, 87627.56186219028, 7948.761537153895, 8066.637001335072, 8165.387595930448, 8212.712555060543, 8376.0, 12317.692057698629, 12855.0, 12919.22484650924, 13218.867811322149, 13318.733625327633, 11096.33421994683, 11189.944050279697, 11343.773124537629, 11435.913588209107, 11582.884171158517, 10947.45262736844, 11069.114470842153, 11081.0, 11222.88777112251, 11457.541698331974, 9231.907680923105, 9445.858312125461, 10215.540300686338, 11664.88335116638, 11718.121140914294, 8037.115917249215, 8125.431219814556, 8191.959040204761, 8205.876911846446, 8225.83548329042, 8789.34079943994, 9013.324000699842, 9354.625814967598, 10377.452643765237, 11435.714107147387], "value": 9380.786929565638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.3906178935738, 1481.1705444950828, 1676.840700133487, 1830.6613276543849, 1842.8709990300683, 7960.164182760799, 8215.301699355547, 8552.657893684252, 9093.13615206556, 9121.680741174061, 8507.191816777397, 8737.825243495126, 8791.51646659435, 8848.646054157825, 8861.069587693297, 2369.7985671217966, 2393.56915755164, 2427.78149966503, 2428.732839387666, 2435.9147429839873, 2385.49904520051, 2405.603075492542, 2410.554047501209, 2434.6591477193206, 2442.218490083174, 2402.37538240058, 2404.759524047599, 2421.1767998880377, 2428.9028438862424, 2440.4143005678598, 2391.5336509380677, 2391.940201494959, 2397.6763136976547, 2409.734929157791, 2421.8425802322968, 2390.6294524348755, 2403.2790162951046, 2408.385861605297, 2411.7105947286263, 2415.6376543518436, 8921.464712117293, 8930.598123084477, 8933.330000250007, 8952.552372381355, 8961.507117108556, 4478.350639157336, 4572.634189264851, 5245.396779370383, 5389.595780316492, 5423.891522169534, 3357.78174418662, 3460.567429071366, 3538.4161613333767, 3550.840212190458, 3566.251087271676, 1074.2694967422149, 1107.6676996900978, 1113.7549739057395, 1119.0655802405001, 1134.4951496584024, 11293.265937714023, 11496.0, 11534.077273818075, 11534.307941523452, 11570.94214528922, 4544.977275113603, 4624.375709279204, 4820.903581928412, 4834.7824347903725, 4834.903301933941, 9724.75688107803, 9967.40195588267, 9984.700458986243, 9987.700368988943, 9991.250656200815, 5362.436944120889, 5382.892342153214, 5394.083005889008, 5397.487238712316, 5412.972935135378, 4409.316555933835, 4579.633629309647, 4778.331033655291, 4915.0, 4943.0, 3589.94615080774, 3699.3711069118312, 3969.64273215412, 4023.7183397161916, 4062.695297852673, 1296.8378952630922, 1307.9542216022585, 1307.9673008174873, 1315.7500074985915, 1318.0971034840973, 766.9501482403626, 831.8544254755394, 857.6183598298821, 888.693400776731, 939.2110627073291, 5581.525570326487, 5949.672767997715, 5973.850653733604, 6009.639421634716, 6123.908141377883, 917.5962576466334, 937.8077494113691, 938.1416004355938, 939.1876027236527, 969.9563519641492, 3943.290207762573, 3951.269015232191, 3989.7207195496035, 4059.350503919418, 4065.3495440729935, 10346.37921724699, 10684.771251306043, 10688.0, 10724.892751072546, 10731.356118632906, 10560.943905609378, 10594.205434592282, 10606.469676516142, 10681.732956676145, 10694.251402401756, 7832.216778322236, 8235.95882020598, 8257.917420825836, 8358.707445239508, 8369.748907532783, 5866.2373891393845, 5930.762769489172, 5938.584299099021, 6036.6679832609625, 6213.254409470893, 10105.141063009432, 10111.696649100539, 10201.74495637615, 10247.333923294962, 10271.075603195748, 8120.0, 10017.0, 10066.094051535105, 10081.798364032826, 10205.285630006125, 7025.332593403618, 7237.819054523679, 7492.0, 7580.279873412238, 7690.730824421118, 8323.542205178774, 8519.659213631634, 8544.35917306217, 8557.871631925402, 8563.186497282748, 7320.890186647098, 7422.814429639302, 7494.025776649, 7565.356944659766, 7640.235976402315, 7294.0, 7357.5217610855125, 7453.919181718729, 7507.061617297838, 7574.128975167926, 6922.550034247758, 7037.085178926707, 7044.788656340318, 7062.187848397294, 7086.39765619928, 4964.826231081895, 5003.249512573143, 5067.594592432522, 5109.3613298337705, 5208.661437006583, 5035.043341765199, 5101.260317254051, 5140.4088529818055, 5149.098907691214, 5233.0], "value": 4618.132933644179, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.5543735991654, 308.192535556841, 319.8848414570754, 320.3049382839239, 320.5224215918282, 5226.294450249225, 5305.469453054692, 5393.838184854456, 5482.2873026506495, 5485.424030476803, 4000.359942409215, 4130.401091841682, 4950.925736113953, 5188.740562971855, 5401.459854014596, 4111.506619205699, 4131.173765246947, 4132.958670413303, 4135.979320103395, 4154.127633197032, 3761.962380376189, 4016.9799151004204, 4129.814158362866, 4143.8342466301465, 4171.895702607444, 3828.6554210121085, 4061.2283666103344, 4079.571644977279, 4080.224757296119, 4088.425956006944, 3894.7273690841653, 4066.247744167309, 4083.224187404384, 4093.1609020151095, 4109.773962432065, 3101.8449077546256, 3289.3585750778607, 3305.603327600679, 3582.498450216972, 3592.209713862946, 5121.667091639031, 5177.922331165036, 5181.171012637959, 5185.740712964337, 5193.558547523465, 3832.2527107214105, 4047.797610119512, 4239.354155426331, 4564.497905230422, 4627.1439783640135, 4541.069080838421, 4713.434387873443, 4892.486288939681, 5490.258815059956, 5643.661380317194, 3133.65529791725, 3837.175007373428, 3838.4434257032854, 3872.5933776953298, 3874.050857539921, 4956.702597844105, 5892.911606325909, 5904.350521442638, 5924.674142922137, 5958.106284057383, 3587.712982961357, 3865.729398942047, 3919.2945269851703, 3927.4501569780255, 3929.351656976567, 2998.130542142798, 3819.1597848473293, 3886.5724770275247, 3890.2219556088894, 3923.6272554107313, 4083.9387409188885, 4227.070044590185, 4609.032103258286, 4632.123989784142, 4645.512221216791, 5489.39616642169, 5737.08206686928, 5776.393478684678, 5818.650880947157, 5836.715922497044, 1302.7459645369063, 1387.6461502317047, 1402.2638114989509, 1404.7963045358365, 1407.465163237963, 780.5199802121767, 935.7052528453515, 939.5208443693629, 966.5795379010153, 972.781124247053, 3755.7793717041523, 3882.5535063467496, 4461.836009535373, 4496.976360637726, 4526.275795872711, 4044.5753195914594, 4265.53079161292, 4553.385292985471, 4561.931571026437, 4694.4977607166065, 4006.6794656427414, 4456.217512994828, 4528.8487968215495, 4529.501754806968, 4596.954030459719, 2085.562031973272, 2241.8454495934607, 2447.968186405315, 2545.8854351554223, 2588.4693637804203, 3933.4296527003426, 4558.452985641744, 4758.476567577563, 4926.975365123223, 5141.820036298712, 4724.055188962221, 5072.163093089673, 5073.797048118034, 5092.134337162691, 5099.796008159707, 4433.6009759121835, 5606.775728970795, 6117.265928088658, 6131.785387511416, 6175.567710260419, 4858.902821943612, 5789.45000224978, 5981.760729570942, 5982.970085149546, 5999.520038397005, 4414.3157810539415, 5169.431362550192, 5257.316548848626, 5898.970505147447, 5926.585139040399, 4903.83702511885, 5019.598432125348, 6028.2766068072115, 6189.319174890668, 6247.625142491465, 3738.981305093457, 3791.943120853134, 3847.672158344273, 3921.3725803871243, 3947.9340578043193, 4250.978745106255, 5076.771545280398, 5181.792728290826, 5849.713063126105, 5880.441358070976, 4912.778924948458, 5166.173412254021, 5668.61917994435, 5714.885702286014, 5736.737917658109, 4252.915506545811, 5219.608529360389, 5326.445888765205, 5401.135818269058, 5714.171445140349, 4814.074370251928, 4993.900121997613, 5364.034473794754, 5486.369067557282, 5580.720963951868, 5120.129577971828, 5418.83743487696, 5673.120666296731, 5681.318241811009, 5731.140328950524, 4162.729422587522, 4966.932109596454, 5307.6284660073425, 5453.809116680897, 5644.435556444323], "value": 3950.8094825833978, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.5167210460831, 737.7049180327868, 773.408342617897, 781.1134362498564, 813.4834380168592, 15065.096094234339, 15727.370905163809, 15785.763213551778, 15809.446669366574, 15880.444184453532, 14135.222562759043, 14252.786208206886, 14261.429542818274, 14267.1439713617, 14272.2863856807, 680.5950459476628, 707.794739525537, 854.6837670062085, 857.9528125953086, 879.3800370738614, 887.3699673232013, 888.4003297774009, 890.3055616619015, 891.01542795211, 892.1435421994893, 928.4939707859199, 950.0546955779009, 950.5437390052779, 950.5959967014026, 952.0, 934.5374039850318, 941.580996456577, 943.1888575824785, 946.0870260198873, 949.126803340928, 701.4353445476389, 708.2952462299993, 708.3553965891015, 710.9146902371697, 712.5226098514008, 14667.413303467849, 14690.55928322152, 14705.264736763225, 14749.483768068174, 14758.631034224123, 14365.066270692372, 14455.927720361437, 14496.710065798625, 14504.129752214796, 14525.055871368433, 11836.940815295955, 11874.0, 11907.16649834512, 11925.761484770255, 11969.581064662782, 3751.835462636471, 3774.6602805747616, 3860.884173474772, 3868.18768058708, 3996.4205190247253, 128431.0, 130073.0, 130087.0, 133078.0, 134093.3295333527, 9110.772230694285, 9366.90633093674, 11094.464753001486, 11123.499442525106, 11130.721731956766, 17283.91358043227, 22883.42791430194, 23070.077196912272, 23102.075916963135, 23250.069997199924, 7762.06855177371, 7872.741170769252, 7892.486988345854, 7900.525968441912, 8070.515769053875, 4240.363945408213, 4317.791018514821, 4341.261985462479, 4365.80353884076, 4498.640108791289, 3273.852676629557, 3291.489819078047, 3584.8566057357934, 3784.829682664287, 3971.5631280559114, 700.7232143303366, 710.6304721544768, 724.4132252875188, 727.6034561164179, 730.444861904956, 547.622140722908, 631.2740348599148, 639.6833567384175, 660.5871330418488, 663.171036204744, 9416.95291523538, 9501.0, 9742.366746161619, 9783.706488805346, 9802.754931126636, 480.5218807286772, 549.3078720811806, 626.8526896179485, 646.1019183335168, 704.1620471638734, 4335.263005289108, 4662.743549104764, 4717.962048349357, 4770.521138447023, 4772.331873537708, 71143.0, 110689.44655276672, 154293.0, 155156.44843551645, 168659.15670421568, 77212.61393692995, 77416.61291693618, 134800.0, 141360.0, 145309.0, 106587.0, 124166.0, 125419.0, 127393.0, 129563.35218324036, 9416.868572793399, 9936.950315248378, 9948.427384360617, 9992.0, 10246.692599221737, 59422.0, 61287.77424451575, 61328.38671613227, 62611.37388626238, 64058.67970660117, 97071.0, 97654.60980145072, 98114.0, 100514.0, 103072.45391319286, 13082.934585327013, 13163.736725265633, 13210.933945330597, 13307.06850520493, 13332.733345333232, 14405.639859003608, 14615.634609134857, 14940.402383904522, 15343.61640958985, 15645.217739112772, 13676.794848077487, 13820.723585528032, 14136.646583835487, 14369.497067602584, 14577.708445830813, 13663.142482931238, 13827.446902123802, 13861.376238069513, 14281.928590356982, 14377.109917672879, 13188.868111319147, 13705.588832335046, 13724.245166515937, 13758.65603359924, 13798.586042418743, 11139.72150696239, 11151.0, 11293.445421650467, 11732.765344692887, 11892.345920974429, 9411.623535058521, 11032.0, 11408.315501069963, 11467.0, 11655.941720291345], "value": 7946.751710178492, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 115.0, 112.0, 105.0, 111.0, 122.0, 117.0, 115.0, 133.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 131.0, 161.0, 118.0, 147.0, 156.0, 142.0, 154.0, 142.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 210.0, 217.0, 197.0, 197.0, 199.0, 200.0, 196.0, 201.0, 195.0], "value": 201.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 87.0, 78.0, 165.0, 53.0, 76.0, 83.0, 85.0, 122.0, 53.0], "value": 87.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 133.0, 74.0, 101.0, 105.0, 123.0, 94.0, 58.0, 112.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 89.0, 87.0, 102.0, 131.0, 89.0, 87.0, 91.0, 87.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 102.0, 152.0, 123.0, 95.0, 96.0, 95.0, 95.0, 97.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 117.0, 151.0, 150.0, 92.0, 90.0, 133.0, 145.0, 141.0, 97.0], "value": 120.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 60.0, 58.0, 60.0, 55.0, 57.0, 57.0, 56.0, 56.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 74.0, 74.0, 90.0, 96.0, 74.0, 72.0, 74.0, 109.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 85.0, 87.0, 106.0, 107.0, 86.0, 87.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 139.0, 138.0, 139.0, 138.0, 140.0, 149.0, 189.0, 163.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 231.0, 245.0, 202.0, 171.0, 171.0, 172.0, 166.0, 167.0, 170.0], "value": 185.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 69.0, 69.0, 68.0, 66.0, 82.0, 91.0, 92.0, 76.0], "value": 74.9, "unit": "score"}]}]} 05:20:20 INFO - Return code: 0 05:20:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:20:20 ERROR - # TBPL SUCCESS # 05:20:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:20:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:20:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:20:20 INFO - 2016-09-09 05:20:20.523 screenresolution[1966:20622] starting screenresolution argv=screenresolution get 05:20:20 INFO - 2016-09-09 05:20:20.525 screenresolution[1966:20622] Display 0: 1600x1200x32@60 05:20:20 INFO - 2016-09-09 05:20:20.531 screenresolution[1967:20625] starting screenresolution argv=screenresolution list 05:20:20 INFO - Available Modes on Display 0 05:20:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:20:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:20:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:20:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:20:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:20:20 INFO - Intel Iris: 05:20:20 INFO - Chipset Model: Intel Iris 05:20:20 INFO - Type: GPU 05:20:20 INFO - Bus: Built-In 05:20:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:20:20 INFO - Vendor: Intel (0x8086) 05:20:20 INFO - Device ID: 0x0a2e 05:20:20 INFO - Revision ID: 0x0009 05:20:20 INFO - Displays: 05:20:20 INFO - Display: 05:20:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:20:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:20:20 INFO - Display Serial Number: 1600x1200 60 05:20:20 INFO - Main Display: Yes 05:20:20 INFO - Mirror: Off 05:20:20 INFO - Online: Yes 05:20:20 INFO - Rotation: Supported 05:20:20 INFO - Return code: 0 05:20:20 INFO - Running post-action listener: _resource_record_post_action 05:20:20 INFO - [mozharness: 2016-09-09 12:20:20.767843Z] Finished run-tests step (success) 05:20:20 INFO - Running post-run listener: _resource_record_post_run 05:20:20 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 24576; Write bytes: 705548800; Read time: 0; Write time: 1986 05:20:20 INFO - TinderboxPrint: CPU usage
54.4% 05:20:20 INFO - TinderboxPrint: I/O read bytes / time
24,576 / 0 05:20:20 INFO - TinderboxPrint: I/O write bytes / time
705,548,800 / 1,986 05:20:20 INFO - TinderboxPrint: CPU idle
2,206.3 (45.6%) 05:20:20 INFO - TinderboxPrint: CPU system
978.6 (20.2%) 05:20:20 INFO - TinderboxPrint: CPU user
1,656.9 (34.2%) 05:20:20 INFO - TinderboxPrint: Swap in / out
405,184,512 / 0 05:20:20 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389972480; Write bytes: 384620032; Read time: 29221; Write time: 774 05:20:20 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 24576; Write bytes: 296786944; Read time: 0; Write time: 1143 05:20:21 INFO - Running post-run listener: _upload_blobber_files 05:20:21 INFO - Blob upload gear active. 05:20:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:20:21 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 05:20:21 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'] 05:20:21 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 05:20:21 INFO - (blobuploader) - INFO - Open directory for files ... 05:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:22 INFO - (blobuploader) - INFO - Done attempting. 05:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:20:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:23 INFO - (blobuploader) - INFO - Done attempting. 05:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:20:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:20:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:20:25 INFO - (blobuploader) - INFO - Done attempting. 05:20:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:20:25 INFO - Return code: 0 05:20:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:20:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:20:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ee36344aeb913c37e5afffe8664f144d3eafdcdb4aafedc0e502da35732c8f05d7df950056ee40235c31918dac88cbbcfcc5d52e886894b2118e3d420a9a76a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/369f22a5d784d8fb55ec21ea5fa559d97b917a57362e520ca130afe521ec15b8ddfe924e1ebd3dbfef9b9811e2ad2b8a10b15b4d3426f11d4e8b292d5b56633a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7532cb104debce5366fd371689579565946151ff164f1aa378c6a482d502924a3188c7170db04756d61d388b03a64cdb52187e31f461d7adbc79cd986a7a6201"} 05:20:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:20:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:20:25 INFO - Contents: 05:20:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ee36344aeb913c37e5afffe8664f144d3eafdcdb4aafedc0e502da35732c8f05d7df950056ee40235c31918dac88cbbcfcc5d52e886894b2118e3d420a9a76a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/369f22a5d784d8fb55ec21ea5fa559d97b917a57362e520ca130afe521ec15b8ddfe924e1ebd3dbfef9b9811e2ad2b8a10b15b4d3426f11d4e8b292d5b56633a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7532cb104debce5366fd371689579565946151ff164f1aa378c6a482d502924a3188c7170db04756d61d388b03a64cdb52187e31f461d7adbc79cd986a7a6201"} 05:20:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:20:25 INFO - Copying logs to upload dir... 05:20:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:20:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.105818 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-09 05:20:25.653128) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 05:20:25.656690) ========= 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.6zkutDNDWR/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.5IRn02qX4z/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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/6ee36344aeb913c37e5afffe8664f144d3eafdcdb4aafedc0e502da35732c8f05d7df950056ee40235c31918dac88cbbcfcc5d52e886894b2118e3d420a9a76a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/369f22a5d784d8fb55ec21ea5fa559d97b917a57362e520ca130afe521ec15b8ddfe924e1ebd3dbfef9b9811e2ad2b8a10b15b4d3426f11d4e8b292d5b56633a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7532cb104debce5366fd371689579565946151ff164f1aa378c6a482d502924a3188c7170db04756d61d388b03a64cdb52187e31f461d7adbc79cd986a7a6201"} build_url:https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011384 build_url: 'https://queue.taskcluster.net/v1/task/HjaObpoHQl6hk3EV4pQP6Q/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/6ee36344aeb913c37e5afffe8664f144d3eafdcdb4aafedc0e502da35732c8f05d7df950056ee40235c31918dac88cbbcfcc5d52e886894b2118e3d420a9a76a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/369f22a5d784d8fb55ec21ea5fa559d97b917a57362e520ca130afe521ec15b8ddfe924e1ebd3dbfef9b9811e2ad2b8a10b15b4d3426f11d4e8b292d5b56633a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7532cb104debce5366fd371689579565946151ff164f1aa378c6a482d502924a3188c7170db04756d61d388b03a64cdb52187e31f461d7adbc79cd986a7a6201"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-09 05:20:25.690579) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:20:25.690869) ========= 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.6zkutDNDWR/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.5IRn02qX4z/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-09 05:20:25.739020) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 05:20:25.739334) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-09 05:20:25.739639) ========= ========= Total master_lag: 0.18 =========