builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0132 starttime: 1485883091.58 results: success (0) buildid: 20170131072109 builduid: ad483a45e6ff414aa4709ad83e215e2e revision: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.575808) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.576220) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.576506) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.604331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.604656) ========= 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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 --2017-01-31 09:18:11-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.3M=0.001s 2017-01-31 09:18:11 (10.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120303 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.748084) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.748423) ========= 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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.019972 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:11.801055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 09:18:11.803455) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 --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 ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 2017-01-31 09:18:11,887 truncating revision to first 12 chars 2017-01-31 09:18:11,887 Setting DEBUG logging. 2017-01-31 09:18:11,888 attempt 1/10 2017-01-31 09:18:11,888 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ee975d32deb9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-31 09:18:12,762 unpacking tar archive at: mozilla-central-ee975d32deb9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.126122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-31 09:18:12.952836) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:12.953173) ========= script_repo_revision: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:12.957315) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:12.957759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-31 09:18:12.977414) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-31 09:18:12.977739) ========= /tools/buildbot/bin/python -u 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', '-u', '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:13 INFO - MultiFileLogger online at 20170131 09:18:13 in /builds/slave/test 09:18:13 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 09:18:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:18:13 INFO - {'append_to_log': False, 09:18:13 INFO - 'base_work_dir': '/builds/slave/test', 09:18:13 INFO - 'blob_upload_branch': 'Firefox', 09:18:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:18:13 INFO - 'branch': 'Firefox', 09:18:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:18:13 INFO - 'config_files': ('talos/mac_config.py',), 09:18:13 INFO - 'default_actions': ('clobber', 09:18:13 INFO - 'read-buildbot-config', 09:18:13 INFO - 'download-and-extract', 09:18:13 INFO - 'populate-webroot', 09:18:13 INFO - 'create-virtualenv', 09:18:13 INFO - 'install', 09:18:13 INFO - 'run-tests'), 09:18:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:18:13 INFO - 'download_minidump_stackwalk': True, 09:18:13 INFO - 'download_symbols': 'ondemand', 09:18:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:18:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:18:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:18:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:18:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:18:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:18:13 INFO - 'installer_path': 'installer.exe', 09:18:13 INFO - 'log_level': 'info', 09:18:13 INFO - 'log_name': 'talos', 09:18:13 INFO - 'log_to_console': True, 09:18:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:18:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:18:13 INFO - 'opt_config_files': (), 09:18:13 INFO - 'pip_index': False, 09:18:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:18:13 INFO - 'cmd': ('bash', 09:18:13 INFO - '-c', 09:18:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:18:13 INFO - 'enabled': True, 09:18:13 INFO - 'halt_on_failure': False, 09:18:13 INFO - 'name': 'check_screen_resolution'},), 09:18:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:18:13 INFO - 'cmd': ('bash', 09:18:13 INFO - '-c', 09:18:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:18:13 INFO - 'enabled': True, 09:18:13 INFO - 'halt_on_failure': False, 09:18:13 INFO - 'name': 'check_screen_resolution'},), 09:18:13 INFO - 'run_cmd_checks_enabled': True, 09:18:13 INFO - 'sps_profile': False, 09:18:13 INFO - 'sps_profile_interval': 0, 09:18:13 INFO - 'suite': 'dromaeojs-e10s', 09:18:13 INFO - 'system_bits': '32', 09:18:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:18:13 INFO - 'title': 't-yosemite-r7-0132', 09:18:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:18:13 INFO - 'use_talos_json': True, 09:18:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:18:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:18:13 INFO - 'work_dir': 'build'} 09:18:13 INFO - [mozharness: 2017-01-31 17:18:13.191734Z] Running clobber step. 09:18:13 INFO - Running pre-action listener: _resource_record_pre_action 09:18:13 INFO - Running main action method: clobber 09:18:13 INFO - rmtree: /builds/slave/test/build 09:18:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:18:14 INFO - Running post-action listener: _resource_record_post_action 09:18:14 INFO - [mozharness: 2017-01-31 17:18:14.089236Z] Finished clobber step (success) 09:18:14 INFO - [mozharness: 2017-01-31 17:18:14.089398Z] Running read-buildbot-config step. 09:18:14 INFO - Running pre-action listener: _resource_record_pre_action 09:18:14 INFO - Running main action method: read_buildbot_config 09:18:14 INFO - Using buildbot properties: 09:18:14 INFO - { 09:18:14 INFO - "project": "", 09:18:14 INFO - "product": "firefox", 09:18:14 INFO - "script_repo_revision": "production", 09:18:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:18:14 INFO - "repository": "", 09:18:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:18:14 INFO - "buildid": "20170131072109", 09:18:14 INFO - "pgo_build": "False", 09:18:14 INFO - "basedir": "/builds/slave/test", 09:18:14 INFO - "buildnumber": 35, 09:18:14 INFO - "slavename": "t-yosemite-r7-0132", 09:18:14 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:18:14 INFO - "platform": "yosemite_r7", 09:18:14 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:18:14 INFO - "branch": "mozilla-central", 09:18:14 INFO - "repo_path": "mozilla-central", 09:18:14 INFO - "revision": "ee975d32deb9eaa5641f45428cd6a4b5b555a8f5", 09:18:14 INFO - "stage_platform": "macosx64", 09:18:14 INFO - "builduid": "ad483a45e6ff414aa4709ad83e215e2e", 09:18:14 INFO - "slavebuilddir": "test" 09:18:14 INFO - } 09:18:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:18:14 INFO - Running post-action listener: _resource_record_post_action 09:18:14 INFO - [mozharness: 2017-01-31 17:18:14.092885Z] Finished read-buildbot-config step (success) 09:18:14 INFO - [mozharness: 2017-01-31 17:18:14.093003Z] Running download-and-extract step. 09:18:14 INFO - Running pre-action listener: _resource_record_pre_action 09:18:14 INFO - Running main action method: download_and_extract 09:18:14 INFO - mkdir: /builds/slave/test/build/tests 09:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:14 INFO - trying https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:18:14 INFO - Downloading https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:18:15 INFO - Downloaded 1199 bytes. 09:18:15 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:18:15 INFO - Using the following test package requirements: 09:18:15 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:18:15 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:18:15 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:18:15 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'jsshell-mac.zip'], 09:18:15 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:18:15 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:18:15 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:18:15 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:18:15 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:18:15 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:18:15 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:18:15 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:18:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:18:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:18:15 INFO - Fetch https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:18:16 INFO - Expected file size: 16371958 09:18:16 INFO - Obtained file size: 16371958 09:18:22 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:18:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:18:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:18:22 INFO - Fetch https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:18:23 INFO - Expected file size: 16371958 09:18:23 INFO - Obtained file size: 16371958 09:18:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:18:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:18:30 INFO - Fetch https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:18:32 INFO - Expected file size: 14403454 09:18:32 INFO - Obtained file size: 14403454 09:18:32 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')]} 09:18:32 INFO - trying https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:18:32 INFO - Downloading https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:18:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:18:34 INFO - Downloaded 70215464 bytes. 09:18:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:18:34 INFO - mkdir: /builds/slave/test/properties 09:18:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:18:34 INFO - Writing to file /builds/slave/test/properties/build_url 09:18:34 INFO - Contents: 09:18:34 INFO - build_url:https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:18:35 INFO - Running post-action listener: _resource_record_post_action 09:18:35 INFO - Running post-action listener: set_extra_try_arguments 09:18:35 INFO - [mozharness: 2017-01-31 17:18:35.093833Z] Finished download-and-extract step (success) 09:18:35 INFO - [mozharness: 2017-01-31 17:18:35.093983Z] Running populate-webroot step. 09:18:35 INFO - Running pre-action listener: _resource_record_pre_action 09:18:35 INFO - Running main action method: populate_webroot 09:18:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:35 INFO - '--tppagecycles', 09:18:35 INFO - '7'], 09:18:35 INFO - 'tests': ['tsvgm']}}, 09:18:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:18:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:18:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:18:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['damp', 'tps']}, 09:18:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'tests': ['damp', 'tps']}, 09:18:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['dromaeo_dom']}, 09:18:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['basic_compositor_video']}, 09:18:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:18:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['a11yr', 09:18:35 INFO - 'ts_paint', 09:18:35 INFO - 'tpaint', 09:18:35 INFO - 'sessionrestore', 09:18:35 INFO - 'sessionrestore_no_auto_restore', 09:18:35 INFO - 'tabpaint']}, 09:18:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tsvgx', 09:18:35 INFO - 'tsvgr_opacity', 09:18:35 INFO - 'tart', 09:18:35 INFO - 'tscrollx', 09:18:35 INFO - 'cart', 09:18:35 INFO - 'tsvg_static']}, 09:18:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:18:35 INFO - 'tsvgr_opacity', 09:18:35 INFO - 'tart', 09:18:35 INFO - 'tscrollx', 09:18:35 INFO - 'cart', 09:18:35 INFO - 'tsvg_static']}, 09:18:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s'], 09:18:35 INFO - 'tests': ['tp5o']}, 09:18:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:18:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--disable-e10s', 09:18:35 INFO - '--xperf_path', 09:18:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:35 INFO - 'tests': ['tp5n']}, 09:18:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:18:35 INFO - 'talos_options': ['--xperf_path', 09:18:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:35 INFO - 'tests': ['tp5n']}}, 09:18:35 INFO - 'talos.zip': {'path': '', 09:18:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:35 INFO - Running post-action listener: _resource_record_post_action 09:18:35 INFO - [mozharness: 2017-01-31 17:18:35.112685Z] Finished populate-webroot step (success) 09:18:35 INFO - [mozharness: 2017-01-31 17:18:35.112799Z] Running create-virtualenv step. 09:18:35 INFO - Running pre-action listener: _resource_record_pre_action 09:18:35 INFO - Running main action method: create_virtualenv 09:18:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:18:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:18:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:18:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:35 INFO - Using real prefix '/tools/python27' 09:18:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:18:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:38 INFO - Installing pip.................done. 09:18:38 INFO - Return code: 0 09:18:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:18:39 INFO - Reading from file tmpfile_stdout 09:18:39 INFO - Output received: 09:18:39 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:18:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:18:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:39 INFO - 'HOME': '/Users/cltbld', 09:18:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:39 INFO - 'LOGNAME': 'cltbld', 09:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:39 INFO - 'MOZ_NO_REMOTE': '1', 09:18:39 INFO - 'NO_EM_RESTART': '1', 09:18:39 INFO - 'PAGER': '/bin/cat', 09:18:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:39 INFO - 'PWD': '/builds/slave/test', 09:18:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:39 INFO - 'SHELL': '/bin/bash', 09:18:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:39 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:39 INFO - 'USER': 'cltbld', 09:18:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:39 INFO - 'XPC_FLAGS': '0x0', 09:18:39 INFO - 'XPC_SERVICE_NAME': '0', 09:18:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:18:39 INFO - Cleaning up... 09:18:39 INFO - Return code: 0 09:18:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:18:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:39 INFO - 'HOME': '/Users/cltbld', 09:18:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:39 INFO - 'LOGNAME': 'cltbld', 09:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:39 INFO - 'MOZ_NO_REMOTE': '1', 09:18:39 INFO - 'NO_EM_RESTART': '1', 09:18:39 INFO - 'PAGER': '/bin/cat', 09:18:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:39 INFO - 'PWD': '/builds/slave/test', 09:18:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:39 INFO - 'SHELL': '/bin/bash', 09:18:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:39 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:39 INFO - 'USER': 'cltbld', 09:18:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:39 INFO - 'XPC_FLAGS': '0x0', 09:18:39 INFO - 'XPC_SERVICE_NAME': '0', 09:18:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:39 INFO - Downloading/unpacking psutil>=3.1.1 09:18:39 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 09:18:39 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 09:18:39 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 09:18:39 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 09:18:39 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 09:18:39 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 09:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:43 INFO - Installing collected packages: psutil 09:18:43 INFO - Running setup.py install for psutil 09:18:43 INFO - building 'psutil._psutil_osx' extension 09:18:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:18:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:18:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:18:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:18:43 INFO - building 'psutil._psutil_posix' extension 09:18:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:18:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:18:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:18:43 INFO - ^ 09:18:43 INFO - 1 warning generated. 09:18:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:44 INFO - Successfully installed psutil 09:18:44 INFO - Cleaning up... 09:18:44 INFO - Return code: 0 09:18:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:44 INFO - 'HOME': '/Users/cltbld', 09:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:44 INFO - 'LOGNAME': 'cltbld', 09:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:44 INFO - 'MOZ_NO_REMOTE': '1', 09:18:44 INFO - 'NO_EM_RESTART': '1', 09:18:44 INFO - 'PAGER': '/bin/cat', 09:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:44 INFO - 'PWD': '/builds/slave/test', 09:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:44 INFO - 'SHELL': '/bin/bash', 09:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:44 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:44 INFO - 'USER': 'cltbld', 09:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:44 INFO - 'XPC_FLAGS': '0x0', 09:18:44 INFO - 'XPC_SERVICE_NAME': '0', 09:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:18:47 INFO - Installing collected packages: mozsystemmonitor 09:18:47 INFO - Running setup.py install for mozsystemmonitor 09:18:47 INFO - Successfully installed mozsystemmonitor 09:18:47 INFO - Cleaning up... 09:18:47 INFO - Return code: 0 09:18:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:47 INFO - 'HOME': '/Users/cltbld', 09:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:47 INFO - 'LOGNAME': 'cltbld', 09:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:47 INFO - 'MOZ_NO_REMOTE': '1', 09:18:47 INFO - 'NO_EM_RESTART': '1', 09:18:47 INFO - 'PAGER': '/bin/cat', 09:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:47 INFO - 'PWD': '/builds/slave/test', 09:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:47 INFO - 'SHELL': '/bin/bash', 09:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:47 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:47 INFO - 'USER': 'cltbld', 09:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:47 INFO - 'XPC_FLAGS': '0x0', 09:18:47 INFO - 'XPC_SERVICE_NAME': '0', 09:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:50 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:50 INFO - warning: no files found matching '*.txt' 09:18:50 INFO - no previously-included directories found matching 'build' 09:18:50 INFO - no previously-included directories found matching 'dist' 09:18:50 INFO - no previously-included directories found matching '.git*' 09:18:50 INFO - Installing collected packages: jsonschema, functools32 09:18:50 INFO - Running setup.py install for functools32 09:18:50 INFO - warning: no files found matching '*.txt' 09:18:50 INFO - no previously-included directories found matching 'build' 09:18:50 INFO - no previously-included directories found matching 'dist' 09:18:50 INFO - no previously-included directories found matching '.git*' 09:18:50 INFO - Successfully installed jsonschema functools32 09:18:50 INFO - Cleaning up... 09:18:50 INFO - Return code: 0 09:18:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:51 INFO - 'HOME': '/Users/cltbld', 09:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:51 INFO - 'LOGNAME': 'cltbld', 09:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:51 INFO - 'MOZ_NO_REMOTE': '1', 09:18:51 INFO - 'NO_EM_RESTART': '1', 09:18:51 INFO - 'PAGER': '/bin/cat', 09:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:51 INFO - 'PWD': '/builds/slave/test', 09:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:51 INFO - 'SHELL': '/bin/bash', 09:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:51 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:51 INFO - 'USER': 'cltbld', 09:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:51 INFO - 'XPC_FLAGS': '0x0', 09:18:51 INFO - 'XPC_SERVICE_NAME': '0', 09:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:18:51 INFO - Cleaning up... 09:18:51 INFO - Return code: 0 09:18:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:51 INFO - 'HOME': '/Users/cltbld', 09:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:51 INFO - 'LOGNAME': 'cltbld', 09:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:51 INFO - 'MOZ_NO_REMOTE': '1', 09:18:51 INFO - 'NO_EM_RESTART': '1', 09:18:51 INFO - 'PAGER': '/bin/cat', 09:18:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:51 INFO - 'PWD': '/builds/slave/test', 09:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:51 INFO - 'SHELL': '/bin/bash', 09:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:51 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:51 INFO - 'USER': 'cltbld', 09:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:51 INFO - 'XPC_FLAGS': '0x0', 09:18:51 INFO - 'XPC_SERVICE_NAME': '0', 09:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:55 INFO - Downloading docopt-0.6.1.tar.gz 09:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:55 INFO - Running setup.py install for blobuploader 09:18:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:55 INFO - Running setup.py install for requests 09:18:56 INFO - Running setup.py install for docopt 09:18:56 INFO - Successfully installed blobuploader requests docopt 09:18:56 INFO - Cleaning up... 09:18:56 INFO - Return code: 0 09:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:56 INFO - 'HOME': '/Users/cltbld', 09:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:56 INFO - 'LOGNAME': 'cltbld', 09:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:56 INFO - 'MOZ_NO_REMOTE': '1', 09:18:56 INFO - 'NO_EM_RESTART': '1', 09:18:56 INFO - 'PAGER': '/bin/cat', 09:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:56 INFO - 'PWD': '/builds/slave/test', 09:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:56 INFO - 'SHELL': '/bin/bash', 09:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:18:56 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:56 INFO - 'USER': 'cltbld', 09:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:56 INFO - 'XPC_FLAGS': '0x0', 09:18:56 INFO - 'XPC_SERVICE_NAME': '0', 09:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-BOMUeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-BoK1HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ANK1wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-zQKLSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-5kYgQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-te7bXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-XkAIIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qk8qqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gNSgAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-DN2cYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-uH4FLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-XH__mh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YpAqs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GsWOnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cV22C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-yEuzNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-xjSEJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:58 INFO - Running setup.py install for manifestparser 09:18:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Running setup.py install for mozcrash 09:18:58 INFO - Running setup.py install for mozdebug 09:18:58 INFO - Running setup.py install for mozdevice 09:18:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:58 INFO - Running setup.py install for mozfile 09:18:59 INFO - Running setup.py install for mozhttpd 09:18:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Running setup.py install for mozinfo 09:18:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Running setup.py install for mozInstall 09:18:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Running setup.py install for mozleak 09:18:59 INFO - Running setup.py install for mozlog 09:18:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:59 INFO - Running setup.py install for moznetwork 09:19:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Running setup.py install for mozprocess 09:19:00 INFO - Running setup.py install for mozprofile 09:19:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Running setup.py install for mozrunner 09:19:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Running setup.py install for mozscreenshot 09:19:00 INFO - Running setup.py install for moztest 09:19:00 INFO - Running setup.py install for mozversion 09:19:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:19:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:19:00 INFO - Cleaning up... 09:19:00 INFO - Return code: 0 09:19:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:00 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 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:19:00 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 09:19:00 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 09:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:00 INFO - 'HOME': '/Users/cltbld', 09:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:00 INFO - 'LOGNAME': 'cltbld', 09:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:00 INFO - 'MOZ_NO_REMOTE': '1', 09:19:00 INFO - 'NO_EM_RESTART': '1', 09:19:00 INFO - 'PAGER': '/bin/cat', 09:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:00 INFO - 'PWD': '/builds/slave/test', 09:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:00 INFO - 'SHELL': '/bin/bash', 09:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:19:00 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:00 INFO - 'USER': 'cltbld', 09:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:00 INFO - 'XPC_FLAGS': '0x0', 09:19:00 INFO - 'XPC_SERVICE_NAME': '0', 09:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-NPIVPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-b6k4lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-5WlYcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7PtNnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-j3xSKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-9i_B_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Z4yRLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:01 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)) 09:19:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WIdmxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kpmZ1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-sioY5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-fpzy2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZTm2kV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-RxQDKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qsZPLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GYiB8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-3fJr8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:02 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-db0_FJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:19:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:05 INFO - Downloading blessings-1.6.tar.gz 09:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:05 INFO - Installing collected packages: blessings 09:19:05 INFO - Running setup.py install for blessings 09:19:05 INFO - Successfully installed blessings 09:19:05 INFO - Cleaning up... 09:19:06 INFO - Return code: 0 09:19:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:06 INFO - Reading from file tmpfile_stdout 09:19:06 INFO - Current package versions: 09:19:06 INFO - blessings == 1.6 09:19:06 INFO - blobuploader == 1.2.4 09:19:06 INFO - docopt == 0.6.1 09:19:06 INFO - functools32 == 3.2.3-2 09:19:06 INFO - jsonschema == 2.5.1 09:19:06 INFO - manifestparser == 1.1 09:19:06 INFO - mozInstall == 1.12 09:19:06 INFO - mozcrash == 1.0 09:19:06 INFO - mozdebug == 0.1 09:19:06 INFO - mozdevice == 0.49 09:19:06 INFO - mozfile == 1.2 09:19:06 INFO - mozhttpd == 0.7 09:19:06 INFO - mozinfo == 0.9 09:19:06 INFO - mozleak == 0.1 09:19:06 INFO - mozlog == 3.4 09:19:06 INFO - moznetwork == 0.27 09:19:06 INFO - mozprocess == 0.24 09:19:06 INFO - mozprofile == 0.28 09:19:06 INFO - mozrunner == 6.13 09:19:06 INFO - mozscreenshot == 0.1 09:19:06 INFO - mozsystemmonitor == 0.3 09:19:06 INFO - moztest == 0.8 09:19:06 INFO - mozversion == 1.4 09:19:06 INFO - psutil == 3.1.1 09:19:06 INFO - requests == 1.2.3 09:19:06 INFO - wsgiref == 0.1.2 09:19:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 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 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:19:07 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 09:19:07 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 09:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:07 INFO - 'HOME': '/Users/cltbld', 09:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:07 INFO - 'LOGNAME': 'cltbld', 09:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:07 INFO - 'MOZ_NO_REMOTE': '1', 09:19:07 INFO - 'NO_EM_RESTART': '1', 09:19:07 INFO - 'PAGER': '/bin/cat', 09:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:07 INFO - 'PWD': '/builds/slave/test', 09:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:07 INFO - 'SHELL': '/bin/bash', 09:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:19:07 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:07 INFO - 'USER': 'cltbld', 09:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:07 INFO - 'XPC_FLAGS': '0x0', 09:19:07 INFO - 'XPC_SERVICE_NAME': '0', 09:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 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)) 09:19:07 INFO - Cleaning up... 09:19:07 INFO - Return code: 0 09:19:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:07 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')]} 09:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 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')]} 09:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:07 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 0x100f39b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f3c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81abd78510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, '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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 09:19:07 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 09:19:07 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 09:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:07 INFO - 'HOME': '/Users/cltbld', 09:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:07 INFO - 'LOGNAME': 'cltbld', 09:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:07 INFO - 'MOZ_NO_REMOTE': '1', 09:19:07 INFO - 'NO_EM_RESTART': '1', 09:19:07 INFO - 'PAGER': '/bin/cat', 09:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:07 INFO - 'PWD': '/builds/slave/test', 09:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:07 INFO - 'SHELL': '/bin/bash', 09:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:19:07 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:07 INFO - 'USER': 'cltbld', 09:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:07 INFO - 'XPC_FLAGS': '0x0', 09:19:07 INFO - 'XPC_SERVICE_NAME': '0', 09:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:19:08 INFO - Cleaning up... 09:19:08 INFO - Return code: 0 09:19:08 INFO - Running post-action listener: _resource_record_post_action 09:19:08 INFO - Running post-action listener: _start_resource_monitoring 09:19:08 INFO - Starting resource monitoring. 09:19:08 INFO - [mozharness: 2017-01-31 17:19:08.143661Z] Finished create-virtualenv step (success) 09:19:08 INFO - [mozharness: 2017-01-31 17:19:08.144603Z] Running install step. 09:19:08 INFO - Running pre-action listener: _resource_record_pre_action 09:19:08 INFO - Running main action method: install 09:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:08 INFO - Reading from file tmpfile_stdout 09:19:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:19:08 INFO - Reading from file tmpfile_stdout 09:19:08 INFO - Output received: 09:19:08 INFO - Usage: mozinstall [options] installer 09:19:08 INFO - Options: 09:19:08 INFO - -h, --help show this help message and exit 09:19:08 INFO - -d DEST, --destination=DEST 09:19:08 INFO - Directory to install application into. [default: 09:19:08 INFO - "/builds/slave/test"] 09:19:08 INFO - --app=APP Application being installed. [default: firefox] 09:19:08 INFO - mkdir: /builds/slave/test/build/application 09:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:19:30 INFO - Reading from file tmpfile_stdout 09:19:30 INFO - Output received: 09:19:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:19:30 INFO - Running post-action listener: _resource_record_post_action 09:19:30 INFO - [mozharness: 2017-01-31 17:19:30.690216Z] Finished install step (success) 09:19:30 INFO - [mozharness: 2017-01-31 17:19:30.690352Z] Running run-tests step. 09:19:30 INFO - Running pre-action listener: _resource_record_pre_action 09:19:30 INFO - Running main action method: run_tests 09:19:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:19:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:19:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:19:30 INFO - 2017-01-31 09:19:30.704 screenresolution[1977:16695] starting screenresolution argv=screenresolution get 09:19:30 INFO - 2017-01-31 09:19:30.707 screenresolution[1977:16695] Display 0: 1600x1200x32@60 09:19:30 INFO - 2017-01-31 09:19:30.713 screenresolution[1978:16698] starting screenresolution argv=screenresolution list 09:19:30 INFO - Available Modes on Display 0 09:19:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:19:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:19:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:19:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:19:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:19:30 INFO - Intel Iris: 09:19:30 INFO - Chipset Model: Intel Iris 09:19:30 INFO - Type: GPU 09:19:30 INFO - Bus: Built-In 09:19:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:19:30 INFO - Vendor: Intel (0x8086) 09:19:30 INFO - Device ID: 0x0a2e 09:19:30 INFO - Revision ID: 0x0009 09:19:30 INFO - Displays: 09:19:30 INFO - Display: 09:19:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:19:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:19:30 INFO - Display Serial Number: 1600x1200 60 09:19:30 INFO - Main Display: Yes 09:19:30 INFO - Mirror: Off 09:19:30 INFO - Online: Yes 09:19:30 INFO - Rotation: Supported 09:19:30 INFO - Return code: 0 09:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:19:30 INFO - Python 2.7.3 09:19:30 INFO - Return code: 0 09:19:30 INFO - grabbing minidump binary from tooltool 09:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d17d30>, '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 0x100c3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d0cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:19:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:19:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:19:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:19:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:19:31 INFO - Return code: 0 09:19:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:19:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:19:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:19:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:19:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:19:31 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/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 09:19:31 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/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --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 09:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DfnVQb5y1/Render', 09:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:31 INFO - 'HOME': '/Users/cltbld', 09:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:31 INFO - 'LOGNAME': 'cltbld', 09:19:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:19:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:31 INFO - 'MOZ_NO_REMOTE': '1', 09:19:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:19:31 INFO - 'NO_EM_RESTART': '1', 09:19:31 INFO - 'PAGER': '/bin/cat', 09:19:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:31 INFO - 'PWD': '/builds/slave/test', 09:19:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:31 INFO - 'SHELL': '/bin/bash', 09:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Kj9KR6xRO/Listeners', 09:19:31 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 09:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:31 INFO - 'USER': 'cltbld', 09:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:31 INFO - 'XPC_FLAGS': '0x0', 09:19:31 INFO - 'XPC_SERVICE_NAME': '0', 09:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:31 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/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 09:19:31 INFO - mozversion application_buildid: 20170131072109 09:19:31 INFO - mozversion application_changeset: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 09:19:31 INFO - mozversion application_display_name: Nightly 09:19:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:19:31 INFO - mozversion application_name: Firefox 09:19:31 INFO - mozversion application_remotingname: firefox 09:19:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:19:31 INFO - mozversion application_vendor: Mozilla 09:19:31 INFO - mozversion application_version: 54.0a1 09:19:31 INFO - mozversion platform_buildid: 20170131072109 09:19:31 INFO - mozversion platform_changeset: ee975d32deb9eaa5641f45428cd6a4b5b555a8f5 09:19:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:19:31 INFO - mozversion platform_version: 54.0a1 09:19:31 INFO - using testdate: 1485883171 09:19:31 INFO - actual date: 1485883171 09:19:31 INFO - starting webserver on 'localhost:52762' 09:19:31 INFO - SUITE-START | Running 2 tests 09:19:31 INFO - TEST-START | dromaeo_css 09:19:31 INFO - operating with platform_type : mac_ 09:19:31 INFO - Initialising browser for dromaeo_css test... 09:19:31 INFO - TEST-INFO | started process 1988 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpT2_hxF/profile http://localhost:52762/getInfo.html) 09:19:34 INFO - PROCESS | 1988 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:19:34 INFO - PROCESS | 1988 | 2017-01-31 09:19:34.191 plugin-container[1991:16864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 09:19:34 INFO - PROCESS | 1988 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:34 INFO - PROCESS | 1988 | 2017-01-31 09:19:34.194 plugin-container[1991:16864] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 09:19:34 INFO - PROCESS | 1988 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:34 INFO - PROCESS | 1988 | 2017-01-31 09:19:34.194 plugin-container[1991:16864] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:34 INFO - PROCESS | 1988 | __metrics Screen width/height:1600/1200 09:19:34 INFO - PROCESS | 1988 | colorDepth:24 09:19:34 INFO - PROCESS | 1988 | Browser inner width/height: 1024/689 09:19:34 INFO - PROCESS | 1988 | __metrics 09:19:34 ERROR - PROCESS | 1988 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3284: TypeError: this.tabs is undefined 09:19:34 INFO - PROCESS | 1988 | 1485883174377 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpT2_hxF/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpT2_hxF/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpT2_hxF/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpT2_hxF/profile/extensions/talos-powers@TalosPowersService.js:55:9 09:19:35 INFO - TEST-INFO | 1988: exit 0 09:19:35 INFO - Browser initialized. 09:19:35 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:35 INFO - TEST-INFO | started process 1992 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpT2_hxF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:37 INFO - PROCESS | 1992 | 2017-01-31 09:19:37.632 plugin-container[1993:16985] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:19:37 INFO - PROCESS | 1992 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:37 INFO - PROCESS | 1992 | 2017-01-31 09:19:37.632 plugin-container[1993:16985] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 09:19:37 INFO - PROCESS | 1992 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:37 INFO - PROCESS | 1992 | 2017-01-31 09:19:37.632 plugin-container[1993:16985] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:47 INFO - PROCESS | 1992 | RSS: Main: 234463232 09:19:47 INFO - PROCESS | 1992 | 09:22:49 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:52762/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52762/tests/dromaeo/cssquery-ext.html) 09:22:51 INFO - PROCESS | 1992 | RSS: Main: 256466944 09:22:51 INFO - PROCESS | 1992 | 09:24:35 INFO - PROCESS | 1992 | ************************* 09:24:35 INFO - PROCESS | 1992 | A coding exception was thrown and uncaught in a Task. 09:24:35 INFO - PROCESS | 1992 | 09:24:35 ERROR - PROCESS | 1992 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:24:35 INFO - PROCESS | 1992 | Full stack: 09:24:35 INFO - PROCESS | 1992 | ************************* 09:25:53 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:52762/tests/dromaeo/cssquery-ext.html (next: http://localhost:52762/tests/dromaeo/cssquery-jquery.html) 09:25:54 INFO - PROCESS | 1992 | RSS: Main: 265891840 09:25:54 INFO - PROCESS | 1992 | 09:28:56 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:52762/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52762/tests/dromaeo/cssquery-mootools.html) 09:28:57 INFO - PROCESS | 1992 | RSS: Main: 277635072 09:28:57 INFO - PROCESS | 1992 | 09:31:59 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:52762/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52762/tests/dromaeo/cssquery-prototype.html) 09:31:59 INFO - PROCESS | 1992 | RSS: Main: 270626816 09:31:59 INFO - PROCESS | 1992 | 09:35:02 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:52762/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52762/tests/dromaeo/cssquery-yui.html) 09:35:03 INFO - PROCESS | 1992 | RSS: Main: 275529728 09:35:03 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:52762/tests/dromaeo/cssquery-yui.html (next: http://localhost:52762/tests/dromaeo/cssquery-dojo.html) 09:38:06 INFO - PROCESS | 1992 | RSS: Main: 277790720 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | __start_tp_report 09:38:06 INFO - PROCESS | 1992 | _x_x_mozilla_page_load 09:38:06 INFO - PROCESS | 1992 | _x_x_mozilla_page_load_details 09:38:06 INFO - PROCESS | 1992 | |i|pagename|runs| 09:38:06 INFO - PROCESS | 1992 | |0;dojo.html;3357.4963755436684;3395.9830200849;3399.4220982432985;3400.4389275769486;3414.095264754842;5700.942990570093;5813.534917206619;5850.268716410444;5880.323762767283;5882.382349853257;4970.801167953277;5739.741711622976;5742.511886489643;5761.510271626907;5784.045632470637;4774.40319960005;5239.952009598077;5253.921191182136;5311.362636483627;5344.118220493614;5282.577393808505;5377.381601115863;5389.003034438621;5389.272448219499;5405.486478784509;5192.9740351298005;5215.921761173586;5312.335958005249;5319.361676598814;5345.224942383372;4966.826161084345;5215.739213039371;5238.319018527567;5324.227986941911;5364.597655175878;5202.225976349024;5224.738763061831;5225.947740522584;5242.135047717122;5256.395514515842;4928.33467481889;5785.479306862402;5836.854078648025;5857.974854400505;5876.353601103875;5643.786585884013;5869.589128760988;6120.53855959212;6122.1735065766;6169.074638804171;6299.307076221658;6312.053192021233;6315.652639104847;6318.368163183645;6402.263739669951;5937.732802023919;5956.52347812174;5985.311689155716;5985.820425387202;6008.48927841134;6204.224471941007;6254.655993920378;6317.557770955989;6407.551471397051;6454.128692626529;6130.184124957599;6144.709610981655;6245.531585131133;6250;6252.499800015988;6308.558400911984;6325.652089135142;6332.746690132435;6333.683315834282;6340.904886426707;6136.6318020918;6142.078688196717;6182.505399568024;6185.938140618626;6229.0967809667345;6231.2522497300615;6234.906476402857;6253.499720022387;6268.27914789805;6272.40412160844;5040.899182016339;5045.394552653632;5047.747612619354;5058;5067.113255180328;3745.419459983707;4081.20416518776;4328.961049348197;4401.691881568324;4523.231050721385;4969.478204788517;6039.66781826995;6082.239720034996;6098;6098.878022439526;5746.798862039808;6245.094461303176;6289.937100628935;6312.274088479791;6343.080253363237;5885.5585831062845;5952.5833191676165;6011.6693581853415;6016.548758843104;6087.208662873798;5540.806071787468;5566.2207290979295;5603.327600687943;5608.270924779753;5636.774529018875;6460.547761656827;6515.54391192612;6516.283208847023;6527.118838956775;6534.4445722113205;6171.1360409543595;6212.223472065992;6350.269718982377;6352.618842869442;6376.9043464349;6348.111264422892;6364.363563643598;6404.103425520524;6428;6503.284638689645;5133.767895704929;6241.3134555197985;6258.874822503615;6267.435930766253;6279.654618995999;6162.722677479434;6267.341929097562;6293.748250069946;6297.52768542352;6310.463610593151;6120.325235636462;6126.448619624255;6136.417040381157;6168.290646575521;6235.033569796688;6048.425399587032;6231.719572619152;6231.813045608639;6232.376762323732;6250.593711408744;5891.528677705859;6027.21646185993;6044.395560443921;6061.545384096298;6144.446999770043;5728.54171666274;6213.347598502092;6214.25428948529;6216.844578885564;6241.282252540835;6095.75616975316;6110.266767987692;6123.142760013513;6124.54065945047;6140.4473597376455 09:38:06 INFO - PROCESS | 1992 | |1;ext.html;427.2373812744251;431.8294273761864;488.97066176029443;542.9158480435534;556.121328301284;38944.41583376248;41575.584244157515;41617;41617;41792;37417.25165496689;42186.578134218646;42797.786011069904;43576.33507644002;43685.34471982923;7723.459357844953;8679.17547832955;9052.954735226314;9202.539873006357;11154;11160.107191424708;11430;11491;11523.539058437653;11547.249428787143;11271.549138034468;11452.942735286311;11477.827832582518;11486.138539609563;11502.137339699515;11442.71393215172;11451.141164412702;11513.539458421654;11513.827292590619;11526.423678816025;11312.321260724382;11401.714957126056;11465.96806287438;11489.655310340702;11522;34840;41807;42122.94692632678;42277;42308.15383692309;3161.9051428457183;3333.96666033339;4002.3396139636925;4044.4944381952255;4080.5919408059253;30212.68751525783;30590.541141882888;31274.37451250985;31473;31739.889103881254;2653.2173008962404;2987.9551806722916;3062.5406189071596;3219;3274.5743053403144;489291;494909;495505;497761;509899;69373.3062669377;71072.64463677649;71154.64422677853;71372.92940605895;71473;75104;75474;75670.24329756742;76071;76134;13675.863241367659;16675.83324166767;17009.659806803793;17027.65944681124;17039.744403833953;10709.303895246783;11487.310761354345;11730.70673233159;12656.493740250471;12812.743745125046;21716.89141554282;22020.559588808454;22087.779122208573;22104;22144.66782998256;8373.413861029669;8730.738077857675;9234;9324.8601270981;9341.859872101924;797.8444203380285;857.7898414888392;859.2266959736287;862.0517430826042;872.7032808845023;27020.729792702215;29356.853215733783;30104.397912042074;30653.69346306553;31409.685903140675;902.7878448564582;1054.8734151901822;1076.39721755817;1087.9184061195283;1212.9629167062212;14339.35472903701;15977.60055998586;15999.440019599491;16134.23139295407;16199.757003644954;616258;624001;627093;627165;628114;63909.68045159744;64035;64090.718185636026;64198;64246;1399.468202083202;1418.6949805791598;1428.9214093224973;1431.2342896550297;1431.2772050114625;29072;29464.410711786073;29865.25336866509;30591.2352191197;30633.54049689301;54670.726646366515;54769.72615136899;55043;55229.17156242579;55445.445545544026;575275;577033;577793.1110344421;578013;579868;26240.34399140037;27080.593791093543;27207.455850882474;27633.86183069072;27894.93155752825;44138.55861441344;44629;45330.32004519869;45778.85552861072;45803.77098114488;35340.823295883354;36875.631243688294;37380.43929341117;37807.62192378041;37816.81091594524;36724.63275367319;36858.63141368552;37700.62299376971;37898.62101378951;38009.61990380061;35213.119672008404;35707.10732231611;36415.27169456543;36470.27059458849;36655.81672091623;26602.866985664947;31756.523652145697;33253.66746332506;33412;33469.16327091842;20863.643863148845;22675.65986510171;23897.880510597337;24241;24356.391090222885 09:38:06 INFO - PROCESS | 1992 | |2;jquery.html;377.3886304187216;391.3738019169328;396.494469551322;410.4704930639474;432.2608339739048;17036.659266814655;19615.11731972061;20628.17487300506;21105.68341474877;21126.04932778025;21100.472488187803;21137.048832802524;21221.045052972615;21348.57302853946;21486.355409337746;5699.658020518772;5885.764569417218;5932.911006334887;5959.344472108065;5971.492423144038;5680.5455563554815;5846.649201047951;5889.469947704706;5901.498372638331;5952.345242023374;5886.970565147169;5892.49913757329;5902.822915312526;5903.793367232148;5910.0543912973935;5116.616253780962;5588.804391846286;5834.941650583482;5874.265716785402;5877.441643043904;5087.262346959707;5170.172772356443;5178.844634660966;5183.403908550527;5196.220566914955;24191.516169676685;24799.380015499577;25011.49977000468;25175.118870839433;25325.366865828317;2726.086760935089;2746.093789049609;2764.7235276472393;2799.3421545936894;2808.648918885139;15480.612984675361;15555.955451582557;15561.37754489819;15823.626456031572;16301.103439310833;1833.2117189608464;1876.4652074158798;1877.2115711401245;1900.6483800496953;1903.3528600275833;131061;132487;132542.674573254;134087;134322.65677343297;11424.885751142549;11574.537018519333;11647.941760291144;11761;11953.701157471132;11317.773644527064;12643.683907902376;12818.487260509663;14538.41846326135;14575.562733118024;8283.792905177419;8444.831103377897;8645.740627781177;8646.221840034426;8677.219050285505;8444.746657600283;8522.403431759842;8543.829123417621;8702.173293537104;8755.781105472413;7043.92956070443;7124.964375178195;7147.96426017877;7148;7152.213256541776;1908.7423197868388;1941.0492455082833;1948.343907678462;1948.9415317540497;1953.4432686684227;1042.3276986343765;1045.5869931377063;1055.129518147516;1065.1798115451213;1090.9345439273668;21595.568088638454;21676.458088547595;21954;21959.67060494094;22039.33881983511;934.1125930366044;959.7744530035436;1007.1640538353149;1057.096182763726;1077.4881931082673;9423.575939082757;11085.556577736961;11326;11564;11578.536858525564;717014;722767;727077;731102;734574;139146;140370;142246;142527.2873635646;144943.82584261033;974.1524873360222;1014.3356101753363;1033.844923261517;1037.7716902281486;1037.9169666426667;18877.811221887605;19052.333168339042;19146.425607231806;19188.616227675087;19224.846509209492;55811.72094139503;56111.71944140253;56567.71716141393;57148;57238.28285151452;58660.706696466244;77192.61403692946;77807.61096194483;77926.2207377919;78319;8020.318272946632;8189.918100818915;8208.58957052133;8268.2558569729;8376.958115209385;10879.645788250691;13100;13315.933420332836;13352;13463.326833658473;11193.104551636012;11479.713007174887;11504.548635409512;11609;11701.648950531498;10196;11133.721656958316;11324;11481.82777258325;11636.76726465449;11399.601013964802;11710.473028713557;11763.823542646696;11895.82156267674;11926.642200733992;7349.191588925107;8318.792030199293;8320.209580089882;8346.791330216793;8588.26999705032;9750.87864895546;10691.732706682395;11028.669139925814;11571.437855889348;11659.18385712992 09:38:06 INFO - PROCESS | 1992 | |3;mootools.html;1261.2999785119257;1407.2260256858724;1420.438926623983;1421.2822524625062;1443.9061461005037;6524.967375163129;7414.369778568828;8116.553589552572;8543.060263371031;8562.91437085629;7901.367890568755;8172.1827817218245;8176.713815016476;8243.505389676624;8297.626606802693;1916.8466522678152;2256.0637335505744;2295.483516208856;2303.37808791627;2321.6865723127416;2051.4871282179456;2120.5016821047043;2268.54629074185;2312.7918487336137;2323.186884590397;2208.61349263879;2226.3321003698907;2301.2175860207526;2302.228753367624;2314.6875171851752;2288.9313320600327;2300.1719381022817;2316.328264803205;2319.5824751544633;2320.582295186866;1738.97391539127;2278.9658155127686;2298.689676893615;2335.322756400642;2337.263761915008;8212.958935205346;8297.875531867026;8328.916710832875;8349.95825020871;8350.206730360605;4369.759663218521;5085.186370180753;5215.973920130413;5225.5035771602015;5231.5291623753665;3349.7990120592845;3471.6701913318193;3484.6341134181052;3548.006558163718;3553.093961039944;1198.862130854954;1207.710149564107;1243.055277988726;1248.5630029489696;1254.4668515880714;10549.630762923338;10594.894051059468;10657.680269591847;10662.520186591544;10728.088112510446;4164.354525048622;4390.429244198298;4459.818148190732;4466.419365482467;4549.294859296748;8380.287675547646;8541.829163416696;8575.571221438902;8584.442011269373;8589.355798315151;5085.593152547787;5092.745362731848;5099.719515426687;5104.004719079818;5123.052235336475;3946.5264168299987;4690.530946905282;4690.835820746258;4737.0052289019;4789.018251258483;3153.8423078845967;3533.363994481018;3625.2598752598374;3627.891163265106;3719.460678201646;1058.9099926506333;1093.578972095733;1100.2683215661496;1109.7724966381872;1119.8824123467082;695.401954319285;750.2235186581862;770.2720928722416;816.3795515408333;842.877782721502;5267.683938963674;5553.611247212656;5666.121751128499;5693.117566777156;5717.628354156966;959.2805395953035;968.8159249742527;970.6408628807386;988.2143695761742;1015.3907655406845;3674.7243956703355;3695.667389934919;3744.88237547317;3836.07934095815;3850.422436634471;8093.4739241950265;9392.389494682824;9712.562934667956;9798.069183427562;9818.950905245429;9718.028197180367;9754.561044753003;9758.756031099138;9768.26737994639;9779.168770654433;7623;7792.025996750406;7792.454528182972;7798.337141342936;7803.765887023284;4581.63346932238;5181.740912954262;5321.042212401805;5519.613627046228;5549.972250138723;8754.912450875665;8907.866382004146;9007.369484136048;9086.591103400231;9154.176124148858;8998.280137588847;9133.680321188727;9209.98690144097;9239.39943903644;9266.305027122859;7068.187158476635;7087.433005359456;7229.710811567478;7232.566046037255;7243.601601911945;7693.346065584488;7715.035620547432;7726.304632583095;7780.727674531364;7795.766127016199;6319.873602528016;6948.409385202314;6954.547954382949;6999.451453647874;7039.260877607778;6906.620135892575;6911.205211400753;6945.0971373721095;6993.020977063114;7006.75476358325;6318.494520438262;6342.556021078481;6394.744210231539;6400.711967961546;6406.935930640634;4841.176999910094;4901.779419926041;4929.433115191656;4976.377952755905;5123.974380128076;4724.196886529367;4962.702237865739;4979.203327467588;5016.021875734197;5069.670471419346 09:38:06 INFO - PROCESS | 1992 | |4;prototype.html;322.59997602972305;370.36852167055173;377.6884070641721;379.4270651316515;383.2220592197838;4737.384140061788;4781.952180478194;4833.519865696701;5110.540051395374;5421.783128674853;4872.756362181885;5271.578273738101;5318.734063296839;5383.327084114486;5486.780528778844;3442.6901578857896;3665.2486240320804;4124.608162224591;4254.936175957363;4314.266574682311;3847.230553889219;3989.501312335958;3999.6800255979597;4325.437693099892;4325.589069038437;3668.284684486526;4203.288878673254;4217.683673724468;4234.386014027964;4256.574342565734;3850.114473671057;4251.893702657428;4273.67947403946;4281.657467402601;4317.611414972667;3147.8740850365957;3807.9238415231816;3815.9809200954096;3841.9039524011846;3863.961360386388;5016.573591244749;5300.0459917214885;5360.9463905360835;5424.484673955968;5450.348266661307;4884.55638774663;4930.28510865926;4975;4978.178600530908;4985.332846926913;5131.742723032881;5549.667019978774;5700.458456446631;5813.651180929158;5849.883002339972;3938.4879965604573;4173.4157217989505;4192.538820729717;4203.97898010511;4216.831326746927;5078.958813443235;5299.629025968184;5488.588355873326;5962.493188079018;6044.639956009906;4049.7772622505454;4252.255855225323;4295.22685916538;4298.376735373354;4300.333448315516;3710.777353358807;3785.3943369060817;4032.697547683935;4246.766427846436;4373.671974601918;4017.337139371971;4186.706930514895;4962.801487940515;4968.527989840959;4982.750862456863;5051.494850514992;5249.133892907627;5731.799387021434;5894.32215295238;5924.940750592526;1213.5934461955167;1222.7554489102215;1391.942123985758;1409.921410121258;1412.7810189420657;936.9859452108087;947.0860619502075;1002.1331548210891;1007.536533194724;1016.6086056868158;4312.525622181557;4601.792919318638;5062.924056139161;5169.25045868347;5238.97380513095;4867.70793752376;5080.949190508121;5304.602154838325;5327.813526526553;5723.370429252779;4403.801828917706;4543.841675204251;4836.903261934812;5163.514627626338;5232.738363081907;2696.7168447313143;2784.123001254639;2918.7956843020784;2956.600858884066;2962.4519463899246;5034.86715489012;5419.888922770901;5656.321241451053;5759.337676167294;5870.941290587125;4836.685615434985;5171.939752350759;5186.6369354144845;5377.489138531833;5764.682942438206;4940.827071052496;5787.710614469344;5810.675729708066;5816.389279125716;5915.319738230072;5031.993601279832;5296.973515132554;5557.360903495988;5683.403242659461;5693.605066758671;5108.795648174031;5123.948760512496;5365.283109404953;5589.944100558942;5589.959664722266;4875.902481950412;5201.817936372209;5736.6558006519745;5858.740370820124;5869.207656966426;3512.631173726722;3715.64701353371;3796.3735983563506;3838.481804956295;4104.988555607671;5460.34475862899;5545.833624991256;5610.5231055360755;5618.606697531133;5649.4633009864;4689.624830013522;4911.926321105258;5023.518062171741;5586.832395028156;5605;4284.8500302489265;5252.185911183773;5564.276644036249;5681.17622944679;5739.2252045973255;4503.729776213438;4934.457209706858;5015.573676237561;5324.733763311897;5687.744051517609;5779.653220806765;5806.361300257053;5819.650820950587;5871.412858714094;5880.235569375954;5191.454897235736;5356.580506165947;5706.2011318414625;5742.109972954198;5770.50950669181 09:38:06 INFO - PROCESS | 1992 | |5;yui.html;698.5459451356618;722.4112348436021;768.2970082374627;821.8150916043891;822.2258545452745;12287.569935052265;15479.148646824435;15674.686506269896;15715.607109822246;16761.748573771372;16433.42483013095;17029;17058.976461412323;17063;17085.743713844302;694.3230350408362;856.0497847389408;882.5146169606722;884.933629977751;885.0264708820279;711.4948386645488;852.9402941794077;876.4478378621492;888.8089060851916;892.5314210039717;924.0805398628374;928.7585227840742;929.3308817651314;930.1163894300408;932.0260327957267;912.083356226991;921.3826736086771;921.5208091792298;922.7831459606954;925.5881132895864;656.0749343425747;682.7917485167022;689.6448329110513;690.5718454558156;691.6921969723475;15301.693966120616;15739.291731872094;15824.050556966504;15851.207439628088;16098.919505402398;13992.790108148385;14198.219097949712;14218.28908554568;14224.502142424966;14225.715485690333;11596.536138554447;11600.709982250426;11701.590444334493;11703.531858725726;11760.895024045085;3691.187938653492;3691.630836916314;3718.200586873808;3750.3811871041244;3792.677622402099;104414;104779;107750.46124769404;110019;110837.9999999992;10544.103751181236;10589.84115238272;10601.681949541526;10790.460476976119;10916;22026.88986555057;22047.448813779785;22058.89705514718;22107.557848842935;22127.778722212894;6736.46108311334;7608.80977975555;7613.961930190314;7659.923400765921;7750.186230445835;4108.732932359387;4161.646260067868;4195.685323600742;4197.580241975778;4200.222958752641;3513.98094552582;3606.8376923038527;3608.6391360863704;3610.891673249807;3612.9638703612627;631.4853394483481;635.7171058878847;646.2858541311783;655.8557117434157;658.5719282466339;600.4145957691217;654.5221987948825;665.6505334699322;667.0361327881166;699.762080892499;8308.916910830936;10132.34139780912;10208.693739187836;10399.064084232456;10417;700.4536461559991;702.8278071872423;710.298834291868;712.5866997141749;721.7798571435762;4821.879453013633;4952.925706114411;4985.401751789808;4993.375828021497;4995.375578052744;73832.63083684548;84418;99254.00745992592;103685;108330.45834770934;77337;91397;93798;94545;95348;54753.17870232029;59907.70046149829;61288.69355653193;84583.57708211543;85825.57087214524;9591.136797688241;10770.461476925966;11010.944945275221;11027.83458248143;11161;37362.8131859339;46153.538464614925;49157;49398;50275.74862125812;56751;58036.419635803104;66212;66644.66710665857;66792.6660366695;13147.211167329991;13296.002799790247;13348.666283342995;13486.05597608158;13665.931670341584;14259.548671597004;14345.282735862957;14923.104613722777;15081.698366032399;15143;13695;13900.304984750519;13961.860381396462;14001.229932353412;14137.787933180805;11282.717932051764;12460.937695311466;13972.30138493051;13986.230757308445;14398.568042958728;12849.871501284868;13671;13672;13672.042956992915;13882.51411200603;11104.61133860311;11250;11349.886501134883;11471.655850324503;11641;8617.612207450806;10514.631987880388;11118.833217501582;11146.665600032013;11251.437428128724 09:38:06 INFO - PROCESS | 1992 | __end_tp_report 09:38:06 INFO - PROCESS | 1992 | __start_cc_report 09:38:06 INFO - PROCESS | 1992 | _x_x_mozilla_cycle_collect,392 09:38:06 INFO - PROCESS | 1992 | __end_cc_report 09:38:06 INFO - PROCESS | 1992 | __startTimestamp1485884286552__endTimestamp 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | ------- Summary: start ------- 09:38:06 INFO - PROCESS | 1992 | Number of tests: 6 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | [#0] dojo.html Cycles:165 Average:5799.33 Median:6087.21 stddev:675.45 (11.1%) stddev-sans-first:649.79 09:38:06 INFO - PROCESS | 1992 | Values: 3357.5 3396.0 3399.4 3400.4 3414.1 5700.9 5813.5 5850.3 5880.3 5882.4 4970.8 5739.7 5742.5 5761.5 5784.0 4774.4 5240.0 5253.9 5311.4 5344.1 5282.6 5377.4 5389.0 5389.3 5405.5 5193.0 5215.9 5312.3 5319.4 5345.2 4966.8 5215.7 5238.3 5324.2 5364.6 5202.2 5224.7 5225.9 5242.1 5256.4 4928.3 5785.5 5836.9 5858.0 5876.4 5643.8 5869.6 6120.5 6122.2 6169.1 6299.3 6312.1 6315.7 6318.4 6402.3 5937.7 5956.5 5985.3 5985.8 6008.5 6204.2 6254.7 6317.6 6407.6 6454.1 6130.2 6144.7 6245.5 6250.0 6252.5 6308.6 6325.7 6332.7 6333.7 6340.9 6136.6 6142.1 6182.5 6185.9 6229.1 6231.3 6234.9 6253.5 6268.3 6272.4 5040.9 5045.4 5047.7 5058.0 5067.1 3745.4 4081.2 4329.0 4401.7 4523.2 4969.5 6039.7 6082.2 6098.0 6098.9 5746.8 6245.1 6289.9 6312.3 6343.1 5885.6 5952.6 6011.7 6016.5 6087.2 5540.8 5566.2 5603.3 5608.3 5636.8 6460.5 6515.5 6516.3 6527.1 6534.4 6171.1 6212.2 6350.3 6352.6 6376.9 6348.1 6364.4 6404.1 6428.0 6503.3 5133.8 6241.3 6258.9 6267.4 6279.7 6162.7 6267.3 6293.7 6297.5 6310.5 6120.3 6126.4 6136.4 6168.3 6235.0 6048.4 6231.7 6231.8 6232.4 6250.6 5891.5 6027.2 6044.4 6061.5 6144.4 5728.5 6213.3 6214.3 6216.8 6241.3 6095.8 6110.3 6123.1 6124.5 6140.4 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | [#1] ext.html Cycles:180 Average:71055.52 Median:36061.19 stddev:151847.18 (421.1%) stddev-sans-first:152180.55 09:38:06 INFO - PROCESS | 1992 | Values: 427.2 431.8 489.0 542.9 556.1 38944.4 41575.6 41617.0 41617.0 41792.0 37417.3 42186.6 42797.8 43576.3 43685.3 7723.5 8679.2 9053.0 9202.5 11154.0 11160.1 11430.0 11491.0 11523.5 11547.2 11271.5 11452.9 11477.8 11486.1 11502.1 11442.7 11451.1 11513.5 11513.8 11526.4 11312.3 11401.7 11466.0 11489.7 11522.0 34840.0 41807.0 42122.9 42277.0 42308.2 3161.9 3334.0 4002.3 4044.5 4080.6 30212.7 30590.5 31274.4 31473.0 31739.9 2653.2 2988.0 3062.5 3219.0 3274.6 489291.0 494909.0 495505.0 497761.0 509899.0 69373.3 71072.6 71154.6 71372.9 71473.0 75104.0 75474.0 75670.2 76071.0 76134.0 13675.9 16675.8 17009.7 17027.7 17039.7 10709.3 11487.3 11730.7 12656.5 12812.7 21716.9 22020.6 22087.8 22104.0 22144.7 8373.4 8730.7 9234.0 9324.9 9341.9 797.8 857.8 859.2 862.1 872.7 27020.7 29356.9 30104.4 30653.7 31409.7 902.8 1054.9 1076.4 1087.9 1213.0 14339.4 15977.6 15999.4 16134.2 16199.8 616258.0 624001.0 627093.0 627165.0 628114.0 63909.7 64035.0 64090.7 64198.0 64246.0 1399.5 1418.7 1428.9 1431.2 1431.3 29072.0 29464.4 29865.3 30591.2 30633.5 54670.7 54769.7 55043.0 55229.2 55445.4 575275.0 577033.0 577793.1 578013.0 579868.0 26240.3 27080.6 27207.5 27633.9 27894.9 44138.6 44629.0 45330.3 45778.9 45803.8 35340.8 36875.6 37380.4 37807.6 37816.8 36724.6 36858.6 37700.6 37898.6 38009.6 35213.1 35707.1 36415.3 36470.3 36655.8 26602.9 31756.5 33253.7 33412.0 33469.2 20863.6 22675.7 23897.9 24241.0 24356.4 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | [#2] jquery.html Cycles:180 Average:39659.84 Median:21636.01 stddev:120744.21 (558.1%) stddev-sans-first:121046.90 09:38:06 INFO - PROCESS | 1992 | Values: 377.4 391.4 396.5 410.5 432.3 17036.7 19615.1 20628.2 21105.7 21126.0 21100.5 21137.0 21221.0 21348.6 21486.4 5699.7 5885.8 5932.9 5959.3 5971.5 5680.5 5846.6 5889.5 5901.5 5952.3 5887.0 5892.5 5902.8 5903.8 5910.1 5116.6 5588.8 5834.9 5874.3 5877.4 5087.3 5170.2 5178.8 5183.4 5196.2 24191.5 24799.4 25011.5 25175.1 25325.4 2726.1 2746.1 2764.7 2799.3 2808.6 15480.6 15556.0 15561.4 15823.6 16301.1 1833.2 1876.5 1877.2 1900.6 1903.4 131061.0 132487.0 132542.7 134087.0 134322.7 11424.9 11574.5 11647.9 11761.0 11953.7 11317.8 12643.7 12818.5 14538.4 14575.6 8283.8 8444.8 8645.7 8646.2 8677.2 8444.7 8522.4 8543.8 8702.2 8755.8 7043.9 7125.0 7148.0 7148.0 7152.2 1908.7 1941.0 1948.3 1948.9 1953.4 1042.3 1045.6 1055.1 1065.2 1090.9 21595.6 21676.5 21954.0 21959.7 22039.3 934.1 959.8 1007.2 1057.1 1077.5 9423.6 11085.6 11326.0 11564.0 11578.5 717014.0 722767.0 727077.0 731102.0 734574.0 139146.0 140370.0 142246.0 142527.3 144943.8 974.2 1014.3 1033.8 1037.8 1037.9 18877.8 19052.3 19146.4 19188.6 19224.8 55811.7 56111.7 56567.7 57148.0 57238.3 58660.7 77192.6 77807.6 77926.2 78319.0 8020.3 8189.9 8208.6 8268.3 8377.0 10879.6 13100.0 13315.9 13352.0 13463.3 11193.1 11479.7 11504.5 11609.0 11701.6 10196.0 11133.7 11324.0 11481.8 11636.8 11399.6 11710.5 11763.8 11895.8 11926.6 7349.2 8318.8 8320.2 8346.8 8588.3 9750.9 10691.7 11028.7 11571.4 11659.2 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | [#3] mootools.html Cycles:180 Average:5302.04 Median:5220.74 stddev:2880.33 (55.2%) stddev-sans-first:2872.40 09:38:06 INFO - PROCESS | 1992 | Values: 1261.3 1407.2 1420.4 1421.3 1443.9 6525.0 7414.4 8116.6 8543.1 8562.9 7901.4 8172.2 8176.7 8243.5 8297.6 1916.8 2256.1 2295.5 2303.4 2321.7 2051.5 2120.5 2268.5 2312.8 2323.2 2208.6 2226.3 2301.2 2302.2 2314.7 2288.9 2300.2 2316.3 2319.6 2320.6 1739.0 2279.0 2298.7 2335.3 2337.3 8213.0 8297.9 8328.9 8350.0 8350.2 4369.8 5085.2 5216.0 5225.5 5231.5 3349.8 3471.7 3484.6 3548.0 3553.1 1198.9 1207.7 1243.1 1248.6 1254.5 10549.6 10594.9 10657.7 10662.5 10728.1 4164.4 4390.4 4459.8 4466.4 4549.3 8380.3 8541.8 8575.6 8584.4 8589.4 5085.6 5092.7 5099.7 5104.0 5123.1 3946.5 4690.5 4690.8 4737.0 4789.0 3153.8 3533.4 3625.3 3627.9 3719.5 1058.9 1093.6 1100.3 1109.8 1119.9 695.4 750.2 770.3 816.4 842.9 5267.7 5553.6 5666.1 5693.1 5717.6 959.3 968.8 970.6 988.2 1015.4 3674.7 3695.7 3744.9 3836.1 3850.4 8093.5 9392.4 9712.6 9798.1 9819.0 9718.0 9754.6 9758.8 9768.3 9779.2 7623.0 7792.0 7792.5 7798.3 7803.8 4581.6 5181.7 5321.0 5519.6 5550.0 8754.9 8907.9 9007.4 9086.6 9154.2 8998.3 9133.7 9210.0 9239.4 9266.3 7068.2 7087.4 7229.7 7232.6 7243.6 7693.3 7715.0 7726.3 7780.7 7795.8 6319.9 6948.4 6954.5 6999.5 7039.3 6906.6 6911.2 6945.1 6993.0 7006.8 6318.5 6342.6 6394.7 6400.7 6406.9 4841.2 4901.8 4929.4 4976.4 5124.0 4724.2 4962.7 4979.2 5016.0 5069.7 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | [#4] prototype.html Cycles:180 Average:4555.03 Median:5000.45 stddev:1339.08 (26.8%) stddev-sans-first:1304.61 09:38:06 INFO - PROCESS | 1992 | Values: 322.6 370.4 377.7 379.4 383.2 4737.4 4782.0 4833.5 5110.5 5421.8 4872.8 5271.6 5318.7 5383.3 5486.8 3442.7 3665.2 4124.6 4254.9 4314.3 3847.2 3989.5 3999.7 4325.4 4325.6 3668.3 4203.3 4217.7 4234.4 4256.6 3850.1 4251.9 4273.7 4281.7 4317.6 3147.9 3807.9 3816.0 3841.9 3864.0 5016.6 5300.0 5360.9 5424.5 5450.3 4884.6 4930.3 4975.0 4978.2 4985.3 5131.7 5549.7 5700.5 5813.7 5849.9 3938.5 4173.4 4192.5 4204.0 4216.8 5079.0 5299.6 5488.6 5962.5 6044.6 4049.8 4252.3 4295.2 4298.4 4300.3 3710.8 3785.4 4032.7 4246.8 4373.7 4017.3 4186.7 4962.8 4968.5 4982.8 5051.5 5249.1 5731.8 5894.3 5924.9 1213.6 1222.8 1391.9 1409.9 1412.8 937.0 947.1 1002.1 1007.5 1016.6 4312.5 4601.8 5062.9 5169.3 5239.0 4867.7 5080.9 5304.6 5327.8 5723.4 4403.8 4543.8 4836.9 5163.5 5232.7 2696.7 2784.1 2918.8 2956.6 2962.5 5034.9 5419.9 5656.3 5759.3 5870.9 4836.7 5171.9 5186.6 5377.5 5764.7 4940.8 5787.7 5810.7 5816.4 5915.3 5032.0 5297.0 5557.4 5683.4 5693.6 5108.8 5123.9 5365.3 5589.9 5590.0 4875.9 5201.8 5736.7 5858.7 5869.2 3512.6 3715.6 3796.4 3838.5 4105.0 5460.3 5545.8 5610.5 5618.6 5649.5 4689.6 4911.9 5023.5 5586.8 5605.0 4284.9 5252.2 5564.3 5681.2 5739.2 4503.7 4934.5 5015.6 5324.7 5687.7 5779.7 5806.4 5819.7 5871.4 5880.2 5191.5 5356.6 5706.2 5742.1 5770.5 09:38:06 INFO - PROCESS | 1992 | 09:38:06 INFO - PROCESS | 1992 | [#5] yui.html Cycles:180 Average:19981.22 Median:3704.92 stddev:28358.95 (765.4%) stddev-sans-first:28401.54 09:38:06 INFO - PROCESS | 1992 | Values: 698.5 722.4 768.3 821.8 822.2 12287.6 15479.1 15674.7 15715.6 16761.7 16433.4 17029.0 17059.0 17063.0 17085.7 694.3 856.0 882.5 884.9 885.0 711.5 852.9 876.4 888.8 892.5 924.1 928.8 929.3 930.1 932.0 912.1 921.4 921.5 922.8 925.6 656.1 682.8 689.6 690.6 691.7 15301.7 15739.3 15824.1 15851.2 16098.9 13992.8 14198.2 14218.3 14224.5 14225.7 11596.5 11600.7 11701.6 11703.5 11760.9 3691.2 3691.6 3718.2 3750.4 3792.7 104414.0 104779.0 107750.5 110019.0 110838.0 10544.1 10589.8 10601.7 10790.5 10916.0 22026.9 22047.4 22058.9 22107.6 22127.8 6736.5 7608.8 7614.0 7659.9 7750.2 4108.7 4161.6 4195.7 4197.6 4200.2 3514.0 3606.8 3608.6 3610.9 3613.0 631.5 635.7 646.3 655.9 658.6 600.4 654.5 665.7 667.0 699.8 8308.9 10132.3 10208.7 10399.1 10417.0 700.5 702.8 710.3 712.6 721.8 4821.9 4952.9 4985.4 4993.4 4995.4 73832.6 84418.0 99254.0 103685.0 108330.5 77337.0 91397.0 93798.0 94545.0 95348.0 54753.2 59907.7 61288.7 84583.6 85825.6 9591.1 10770.5 11010.9 11027.8 11161.0 37362.8 46153.5 49157.0 49398.0 50275.7 56751.0 58036.4 66212.0 66644.7 66792.7 13147.2 13296.0 13348.7 13486.1 13665.9 14259.5 14345.3 14923.1 15081.7 15143.0 13695.0 13900.3 13961.9 14001.2 14137.8 11282.7 12460.9 13972.3 13986.2 14398.6 12849.9 13671.0 13672.0 13672.0 13882.5 11104.6 11250.0 11349.9 11471.7 11641.0 8617.6 10514.6 11118.8 11146.7 11251.4 09:38:06 INFO - PROCESS | 1992 | -------- Summary: end -------- 09:38:06 INFO - PROCESS | 1992 | 09:38:07 INFO - TEST-INFO | 1992: exit 0 09:38:07 INFO - TEST-OK | dromaeo_css | took 1115924ms 09:38:07 INFO - TEST-START | kraken 09:38:07 INFO - operating with platform_type : mac_ 09:38:07 INFO - Initialising browser for kraken test... 09:38:07 INFO - TEST-INFO | started process 1999 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpZndpKK/profile http://localhost:52762/getInfo.html) 09:38:09 INFO - PROCESS | 1999 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:38:10 INFO - PROCESS | 1999 | 2017-01-31 09:38:10.025 plugin-container[2000:18920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 09:38:10 INFO - PROCESS | 1999 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:10 INFO - PROCESS | 1999 | 2017-01-31 09:38:10.028 plugin-container[2000:18920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 09:38:10 INFO - PROCESS | 1999 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:10 INFO - PROCESS | 1999 | 2017-01-31 09:38:10.028 plugin-container[2000:18920] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:10 INFO - PROCESS | 1999 | __metrics Screen width/height:1600/1200 09:38:10 INFO - PROCESS | 1999 | colorDepth:24 09:38:10 INFO - PROCESS | 1999 | Browser inner width/height: 1024/689 09:38:10 INFO - PROCESS | 1999 | __metrics 09:38:10 ERROR - PROCESS | 1999 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3284: TypeError: this.tabs is undefined 09:38:10 INFO - PROCESS | 1999 | 1485884290192 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpZndpKK/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpZndpKK/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpZndpKK/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpZndpKK/profile/extensions/talos-powers@TalosPowersService.js:55:9 09:38:11 INFO - TEST-INFO | 1999: exit 0 09:38:11 INFO - Browser initialized. 09:38:11 INFO - Running cycle 1/1 for kraken test... 09:38:11 INFO - TEST-INFO | started process 2001 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpZndpKK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:13 INFO - PROCESS | 2001 | 2017-01-31 09:38:13.722 plugin-container[2002:19033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 09:38:13 INFO - PROCESS | 2001 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:13 INFO - PROCESS | 2001 | 2017-01-31 09:38:13.723 plugin-container[2002:19033] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.CFPasteboardClient' 09:38:13 INFO - PROCESS | 2001 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:13 INFO - PROCESS | 2001 | 2017-01-31 09:38:13.723 plugin-container[2002:19033] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:23 INFO - PROCESS | 2001 | RSS: Main: 234459136 09:38:23 INFO - PROCESS | 2001 | 09:38:26 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52762/tests/kraken/driver.html?testName=audio-beat-detection) 09:38:27 INFO - PROCESS | 2001 | RSS: Main: 247840768 09:38:27 INFO - PROCESS | 2001 | 09:38:29 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52762/tests/kraken/driver.html?testName=audio-dft) 09:38:30 INFO - PROCESS | 2001 | RSS: Main: 256864256 09:38:30 INFO - PROCESS | 2001 | 09:38:33 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52762/tests/kraken/driver.html?testName=audio-fft) 09:38:34 INFO - PROCESS | 2001 | RSS: Main: 258797568 09:38:34 INFO - PROCESS | 2001 | 09:38:36 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52762/tests/kraken/driver.html?testName=audio-oscillator) 09:38:37 INFO - PROCESS | 2001 | RSS: Main: 261029888 09:38:37 INFO - PROCESS | 2001 | 09:38:39 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52762/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:39 INFO - PROCESS | 2001 | RSS: Main: 261406720 09:38:39 INFO - PROCESS | 2001 | 09:38:42 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52762/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:43 INFO - PROCESS | 2001 | RSS: Main: 266690560 09:38:43 INFO - PROCESS | 2001 | 09:38:46 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52762/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:47 INFO - PROCESS | 2001 | RSS: Main: 266477568 09:38:47 INFO - PROCESS | 2001 | 09:38:50 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52762/tests/kraken/driver.html?testName=json-parse-financial) 09:38:50 INFO - PROCESS | 2001 | RSS: Main: 266248192 09:38:50 INFO - PROCESS | 2001 | 09:38:52 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52762/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:53 INFO - PROCESS | 2001 | RSS: Main: 265732096 09:38:53 INFO - PROCESS | 2001 | 09:38:55 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:55 INFO - PROCESS | 2001 | RSS: Main: 265719808 09:38:55 INFO - PROCESS | 2001 | 09:38:57 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:58 INFO - PROCESS | 2001 | RSS: Main: 266018816 09:38:58 INFO - PROCESS | 2001 | 09:39:01 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:39:02 INFO - PROCESS | 2001 | RSS: Main: 266133504 09:39:02 INFO - PROCESS | 2001 | 09:39:05 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:39:05 INFO - PROCESS | 2001 | RSS: Main: 265940992 09:39:05 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:52762/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52762/tests/kraken/driver.html?testName=ai-astar) 09:39:07 INFO - PROCESS | 2001 | RSS: Main: 267894784 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | __start_tp_report 09:39:07 INFO - PROCESS | 2001 | _x_x_mozilla_page_load 09:39:07 INFO - PROCESS | 2001 | _x_x_mozilla_page_load_details 09:39:07 INFO - PROCESS | 2001 | |i|pagename|runs| 09:39:07 INFO - PROCESS | 2001 | |0;ai-astar;105;117;116;116;117;116;119;117;117;116 09:39:07 INFO - PROCESS | 2001 | |1;audio-beat-detection;144;112;111;148;111;111;125;166;169;111 09:39:07 INFO - PROCESS | 2001 | |2;audio-dft;196;205;226;200;198;197;195;197;205;190 09:39:07 INFO - PROCESS | 2001 | |3;audio-fft;84;58;58;86;60;60;58;59;75;83 09:39:07 INFO - PROCESS | 2001 | |4;audio-oscillator;87;70;88;72;92;65;87;103;105;114 09:39:07 INFO - PROCESS | 2001 | |5;imaging-gaussian-blur;89;112;85;108;109;86;135;107;106;131 09:39:07 INFO - PROCESS | 2001 | |6;imaging-darkroom;97;108;120;151;94;151;95;95;105;97 09:39:07 INFO - PROCESS | 2001 | |7;imaging-desaturate;91;95;91;89;94;92;92;96;91;94 09:39:07 INFO - PROCESS | 2001 | |8;json-parse-financial;63;65;63;66;64;62;64;65;64;64 09:39:07 INFO - PROCESS | 2001 | |9;json-stringify-tinderbox;71;73;74;80;77;71;70;69;76;72 09:39:07 INFO - PROCESS | 2001 | |10;stanford-crypto-aes;92;87;90;88;90;86;89;87;89;89 09:39:07 INFO - PROCESS | 2001 | |11;stanford-crypto-ccm;149;141;149;138;154;148;151;161;204;162 09:39:07 INFO - PROCESS | 2001 | |12;stanford-crypto-pbkdf2;175;176;173;226;202;174;175;172;171;175 09:39:07 INFO - PROCESS | 2001 | |13;stanford-crypto-sha256-iterative;68;71;82;94;88;91;77;67;66;70 09:39:07 INFO - PROCESS | 2001 | __end_tp_report 09:39:07 INFO - PROCESS | 2001 | __start_cc_report 09:39:07 INFO - PROCESS | 2001 | _x_x_mozilla_cycle_collect,932 09:39:07 INFO - PROCESS | 2001 | __end_cc_report 09:39:07 INFO - PROCESS | 2001 | __startTimestamp1485884347984__endTimestamp 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | ------- Summary: start ------- 09:39:07 INFO - PROCESS | 2001 | Number of tests: 14 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | [#0] ai-astar Cycles:10 Average:115.60 Median:117.00 stddev:3.84 (3.3%) stddev-sans-first:0.97 09:39:07 INFO - PROCESS | 2001 | Values: 105.0 117.0 116.0 116.0 117.0 116.0 119.0 117.0 117.0 116.0 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | [#1] audio-beat-detection Cycles:10 Average:130.80 Median:134.50 stddev:23.86 (17.7%) stddev-sans-first:24.82 09:39:07 INFO - PROCESS | 2001 | Values: 144.0 112.0 111.0 148.0 111.0 111.0 125.0 166.0 169.0 111.0 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | [#2] audio-dft Cycles:10 Average:200.90 Median:199.00 stddev:9.89 (5.0%) stddev-sans-first:10.33 09:39:07 INFO - PROCESS | 2001 | Values: 196.0 205.0 226.0 200.0 198.0 197.0 195.0 197.0 205.0 190.0 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | [#3] audio-fft Cycles:10 Average:68.10 Median:67.50 stddev:12.31 (18.2%) stddev-sans-first:11.63 09:39:07 INFO - PROCESS | 2001 | Values: 84.0 58.0 58.0 86.0 60.0 60.0 58.0 59.0 75.0 83.0 09:39:07 INFO - PROCESS | 2001 | 09:39:07 INFO - PROCESS | 2001 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:79.50 stddev:16.04 (20.2%) stddev-sans-first:17.01 09:39:07 INFO - PROCESS | 2001 | Values: 87.0 70.0 88.0 72.0 92.0 65.0 87.0 103.0 105.0 114.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#5] imaging-gaussian-blur Cycles:10 Average:106.80 Median:133.00 stddev:17.13 (12.9%) stddev-sans-first:16.91 09:39:08 INFO - PROCESS | 2001 | Values: 89.0 112.0 85.0 108.0 109.0 86.0 135.0 107.0 106.0 131.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#6] imaging-darkroom Cycles:10 Average:111.30 Median:94.50 stddev:22.41 (23.7%) stddev-sans-first:23.16 09:39:08 INFO - PROCESS | 2001 | Values: 97.0 108.0 120.0 151.0 94.0 151.0 95.0 95.0 105.0 97.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:93.00 stddev:2.17 (2.3%) stddev-sans-first:2.24 09:39:08 INFO - PROCESS | 2001 | Values: 91.0 95.0 91.0 89.0 94.0 92.0 92.0 96.0 91.0 94.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:1.15 (1.8%) stddev-sans-first:1.17 09:39:08 INFO - PROCESS | 2001 | Values: 63.0 65.0 63.0 66.0 64.0 62.0 64.0 65.0 64.0 64.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.50 stddev:3.47 (4.7%) stddev-sans-first:3.57 09:39:08 INFO - PROCESS | 2001 | Values: 71.0 73.0 74.0 80.0 77.0 71.0 70.0 69.0 76.0 72.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:89.00 stddev:1.77 (2.0%) stddev-sans-first:1.41 09:39:08 INFO - PROCESS | 2001 | Values: 92.0 87.0 90.0 88.0 90.0 86.0 89.0 87.0 89.0 89.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#11] stanford-crypto-ccm Cycles:10 Average:155.70 Median:152.50 stddev:18.57 (12.2%) stddev-sans-first:19.54 09:39:08 INFO - PROCESS | 2001 | Values: 149.0 141.0 149.0 138.0 154.0 148.0 151.0 161.0 204.0 162.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.90 Median:175.00 stddev:17.90 (10.2%) stddev-sans-first:18.81 09:39:08 INFO - PROCESS | 2001 | Values: 175.0 176.0 173.0 226.0 202.0 174.0 175.0 172.0 171.0 175.0 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - PROCESS | 2001 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:79.50 stddev:10.63 (13.4%) stddev-sans-first:10.71 09:39:08 INFO - PROCESS | 2001 | Values: 68.0 71.0 82.0 94.0 88.0 91.0 77.0 67.0 66.0 70.0 09:39:08 INFO - PROCESS | 2001 | -------- Summary: end -------- 09:39:08 INFO - PROCESS | 2001 | 09:39:08 INFO - TEST-INFO | 2001: exit 0 09:39:08 INFO - TEST-OK | kraken | took 61715ms 09:39:08 INFO - SUITE-END | took 1177s 09:39:09 INFO - Completed test suite (00:19:37) 09:39:09 INFO - javascript benchmark 09:39:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7373.362594540969, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.4963755436684, 3395.9830200849, 3399.4220982432985, 3400.4389275769486, 3414.095264754842, 5700.942990570093, 5813.534917206619, 5850.268716410444, 5880.323762767283, 5882.382349853257, 4970.801167953277, 5739.741711622976, 5742.511886489643, 5761.510271626907, 5784.045632470637, 4774.40319960005, 5239.952009598077, 5253.921191182136, 5311.362636483627, 5344.118220493614, 5282.577393808505, 5377.381601115863, 5389.003034438621, 5389.272448219499, 5405.486478784509, 5192.9740351298005, 5215.921761173586, 5312.335958005249, 5319.361676598814, 5345.224942383372, 4966.826161084345, 5215.739213039371, 5238.319018527567, 5324.227986941911, 5364.597655175878, 5202.225976349024, 5224.738763061831, 5225.947740522584, 5242.135047717122, 5256.395514515842, 4928.33467481889, 5785.479306862402, 5836.854078648025, 5857.974854400505, 5876.353601103875, 5643.786585884013, 5869.589128760988, 6120.53855959212, 6122.1735065766, 6169.074638804171, 6299.307076221658, 6312.053192021233, 6315.652639104847, 6318.368163183645, 6402.263739669951, 5937.732802023919, 5956.52347812174, 5985.311689155716, 5985.820425387202, 6008.48927841134, 6204.224471941007, 6254.655993920378, 6317.557770955989, 6407.551471397051, 6454.128692626529, 6130.184124957599, 6144.709610981655, 6245.531585131133, 6250.0, 6252.499800015988, 6308.558400911984, 6325.652089135142, 6332.746690132435, 6333.683315834282, 6340.904886426707, 6136.6318020918, 6142.078688196717, 6182.505399568024, 6185.938140618626, 6229.0967809667345, 6231.2522497300615, 6234.906476402857, 6253.499720022387, 6268.27914789805, 6272.40412160844, 5040.899182016339, 5045.394552653632, 5047.747612619354, 5058.0, 5067.113255180328, 3745.419459983707, 4081.20416518776, 4328.961049348197, 4401.691881568324, 4523.231050721385, 4969.478204788517, 6039.66781826995, 6082.239720034996, 6098.0, 6098.878022439526, 5746.798862039808, 6245.094461303176, 6289.937100628935, 6312.274088479791, 6343.080253363237, 5885.5585831062845, 5952.5833191676165, 6011.6693581853415, 6016.548758843104, 6087.208662873798, 5540.806071787468, 5566.2207290979295, 5603.327600687943, 5608.270924779753, 5636.774529018875, 6460.547761656827, 6515.54391192612, 6516.283208847023, 6527.118838956775, 6534.4445722113205, 6171.1360409543595, 6212.223472065992, 6350.269718982377, 6352.618842869442, 6376.9043464349, 6348.111264422892, 6364.363563643598, 6404.103425520524, 6428.0, 6503.284638689645, 5133.767895704929, 6241.3134555197985, 6258.874822503615, 6267.435930766253, 6279.654618995999, 6162.722677479434, 6267.341929097562, 6293.748250069946, 6297.52768542352, 6310.463610593151, 6120.325235636462, 6126.448619624255, 6136.417040381157, 6168.290646575521, 6235.033569796688, 6048.425399587032, 6231.719572619152, 6231.813045608639, 6232.376762323732, 6250.593711408744, 5891.528677705859, 6027.21646185993, 6044.395560443921, 6061.545384096298, 6144.446999770043, 5728.54171666274, 6213.347598502092, 6214.25428948529, 6216.844578885564, 6241.282252540835, 6095.75616975316, 6110.266767987692, 6123.142760013513, 6124.54065945047, 6140.4473597376455], "value": 5755.3477974393, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.2373812744251, 431.8294273761864, 488.97066176029443, 542.9158480435534, 556.121328301284, 38944.41583376248, 41575.584244157515, 41617.0, 41617.0, 41792.0, 37417.25165496689, 42186.578134218646, 42797.786011069904, 43576.33507644002, 43685.34471982923, 7723.459357844953, 8679.17547832955, 9052.954735226314, 9202.539873006357, 11154.0, 11160.107191424708, 11430.0, 11491.0, 11523.539058437653, 11547.249428787143, 11271.549138034468, 11452.942735286311, 11477.827832582518, 11486.138539609563, 11502.137339699515, 11442.71393215172, 11451.141164412702, 11513.539458421654, 11513.827292590619, 11526.423678816025, 11312.321260724382, 11401.714957126056, 11465.96806287438, 11489.655310340702, 11522.0, 34840.0, 41807.0, 42122.94692632678, 42277.0, 42308.15383692309, 3161.9051428457183, 3333.96666033339, 4002.3396139636925, 4044.4944381952255, 4080.5919408059253, 30212.68751525783, 30590.541141882888, 31274.37451250985, 31473.0, 31739.889103881254, 2653.2173008962404, 2987.9551806722916, 3062.5406189071596, 3219.0, 3274.5743053403144, 489291.0, 494909.0, 495505.0, 497761.0, 509899.0, 69373.3062669377, 71072.64463677649, 71154.64422677853, 71372.92940605895, 71473.0, 75104.0, 75474.0, 75670.24329756742, 76071.0, 76134.0, 13675.863241367659, 16675.83324166767, 17009.659806803793, 17027.65944681124, 17039.744403833953, 10709.303895246783, 11487.310761354345, 11730.70673233159, 12656.493740250471, 12812.743745125046, 21716.89141554282, 22020.559588808454, 22087.779122208573, 22104.0, 22144.66782998256, 8373.413861029669, 8730.738077857675, 9234.0, 9324.8601270981, 9341.859872101924, 797.8444203380285, 857.7898414888392, 859.2266959736287, 862.0517430826042, 872.7032808845023, 27020.729792702215, 29356.853215733783, 30104.397912042074, 30653.69346306553, 31409.685903140675, 902.7878448564582, 1054.8734151901822, 1076.39721755817, 1087.9184061195283, 1212.9629167062212, 14339.35472903701, 15977.60055998586, 15999.440019599491, 16134.23139295407, 16199.757003644954, 616258.0, 624001.0, 627093.0, 627165.0, 628114.0, 63909.68045159744, 64035.0, 64090.718185636026, 64198.0, 64246.0, 1399.468202083202, 1418.6949805791598, 1428.9214093224973, 1431.2342896550297, 1431.2772050114625, 29072.0, 29464.410711786073, 29865.25336866509, 30591.2352191197, 30633.54049689301, 54670.726646366515, 54769.72615136899, 55043.0, 55229.17156242579, 55445.445545544026, 575275.0, 577033.0, 577793.1110344421, 578013.0, 579868.0, 26240.34399140037, 27080.593791093543, 27207.455850882474, 27633.86183069072, 27894.93155752825, 44138.55861441344, 44629.0, 45330.32004519869, 45778.85552861072, 45803.77098114488, 35340.823295883354, 36875.631243688294, 37380.43929341117, 37807.62192378041, 37816.81091594524, 36724.63275367319, 36858.63141368552, 37700.62299376971, 37898.62101378951, 38009.61990380061, 35213.119672008404, 35707.10732231611, 36415.27169456543, 36470.27059458849, 36655.81672091623, 26602.866985664947, 31756.523652145697, 33253.66746332506, 33412.0, 33469.16327091842, 20863.643863148845, 22675.65986510171, 23897.880510597337, 24241.0, 24356.391090222885], "value": 20610.045123038544, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.3886304187216, 391.3738019169328, 396.494469551322, 410.4704930639474, 432.2608339739048, 17036.659266814655, 19615.11731972061, 20628.17487300506, 21105.68341474877, 21126.04932778025, 21100.472488187803, 21137.048832802524, 21221.045052972615, 21348.57302853946, 21486.355409337746, 5699.658020518772, 5885.764569417218, 5932.911006334887, 5959.344472108065, 5971.492423144038, 5680.5455563554815, 5846.649201047951, 5889.469947704706, 5901.498372638331, 5952.345242023374, 5886.970565147169, 5892.49913757329, 5902.822915312526, 5903.793367232148, 5910.0543912973935, 5116.616253780962, 5588.804391846286, 5834.941650583482, 5874.265716785402, 5877.441643043904, 5087.262346959707, 5170.172772356443, 5178.844634660966, 5183.403908550527, 5196.220566914955, 24191.516169676685, 24799.380015499577, 25011.49977000468, 25175.118870839433, 25325.366865828317, 2726.086760935089, 2746.093789049609, 2764.7235276472393, 2799.3421545936894, 2808.648918885139, 15480.612984675361, 15555.955451582557, 15561.37754489819, 15823.626456031572, 16301.103439310833, 1833.2117189608464, 1876.4652074158798, 1877.2115711401245, 1900.6483800496953, 1903.3528600275833, 131061.0, 132487.0, 132542.674573254, 134087.0, 134322.65677343297, 11424.885751142549, 11574.537018519333, 11647.941760291144, 11761.0, 11953.701157471132, 11317.773644527064, 12643.683907902376, 12818.487260509663, 14538.41846326135, 14575.562733118024, 8283.792905177419, 8444.831103377897, 8645.740627781177, 8646.221840034426, 8677.219050285505, 8444.746657600283, 8522.403431759842, 8543.829123417621, 8702.173293537104, 8755.781105472413, 7043.92956070443, 7124.964375178195, 7147.96426017877, 7148.0, 7152.213256541776, 1908.7423197868388, 1941.0492455082833, 1948.343907678462, 1948.9415317540497, 1953.4432686684227, 1042.3276986343765, 1045.5869931377063, 1055.129518147516, 1065.1798115451213, 1090.9345439273668, 21595.568088638454, 21676.458088547595, 21954.0, 21959.67060494094, 22039.33881983511, 934.1125930366044, 959.7744530035436, 1007.1640538353149, 1057.096182763726, 1077.4881931082673, 9423.575939082757, 11085.556577736961, 11326.0, 11564.0, 11578.536858525564, 717014.0, 722767.0, 727077.0, 731102.0, 734574.0, 139146.0, 140370.0, 142246.0, 142527.2873635646, 144943.82584261033, 974.1524873360222, 1014.3356101753363, 1033.844923261517, 1037.7716902281486, 1037.9169666426667, 18877.811221887605, 19052.333168339042, 19146.425607231806, 19188.616227675087, 19224.846509209492, 55811.72094139503, 56111.71944140253, 56567.71716141393, 57148.0, 57238.28285151452, 58660.706696466244, 77192.61403692946, 77807.61096194483, 77926.2207377919, 78319.0, 8020.318272946632, 8189.918100818915, 8208.58957052133, 8268.2558569729, 8376.958115209385, 10879.645788250691, 13100.0, 13315.933420332836, 13352.0, 13463.326833658473, 11193.104551636012, 11479.713007174887, 11504.548635409512, 11609.0, 11701.648950531498, 10196.0, 11133.721656958316, 11324.0, 11481.82777258325, 11636.76726465449, 11399.601013964802, 11710.473028713557, 11763.823542646696, 11895.82156267674, 11926.642200733992, 7349.191588925107, 8318.792030199293, 8320.209580089882, 8346.791330216793, 8588.26999705032, 9750.87864895546, 10691.732706682395, 11028.669139925814, 11571.437855889348, 11659.18385712992], "value": 10075.822621235735, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1261.2999785119257, 1407.2260256858724, 1420.438926623983, 1421.2822524625062, 1443.9061461005037, 6524.967375163129, 7414.369778568828, 8116.553589552572, 8543.060263371031, 8562.91437085629, 7901.367890568755, 8172.1827817218245, 8176.713815016476, 8243.505389676624, 8297.626606802693, 1916.8466522678152, 2256.0637335505744, 2295.483516208856, 2303.37808791627, 2321.6865723127416, 2051.4871282179456, 2120.5016821047043, 2268.54629074185, 2312.7918487336137, 2323.186884590397, 2208.61349263879, 2226.3321003698907, 2301.2175860207526, 2302.228753367624, 2314.6875171851752, 2288.9313320600327, 2300.1719381022817, 2316.328264803205, 2319.5824751544633, 2320.582295186866, 1738.97391539127, 2278.9658155127686, 2298.689676893615, 2335.322756400642, 2337.263761915008, 8212.958935205346, 8297.875531867026, 8328.916710832875, 8349.95825020871, 8350.206730360605, 4369.759663218521, 5085.186370180753, 5215.973920130413, 5225.5035771602015, 5231.5291623753665, 3349.7990120592845, 3471.6701913318193, 3484.6341134181052, 3548.006558163718, 3553.093961039944, 1198.862130854954, 1207.710149564107, 1243.055277988726, 1248.5630029489696, 1254.4668515880714, 10549.630762923338, 10594.894051059468, 10657.680269591847, 10662.520186591544, 10728.088112510446, 4164.354525048622, 4390.429244198298, 4459.818148190732, 4466.419365482467, 4549.294859296748, 8380.287675547646, 8541.829163416696, 8575.571221438902, 8584.442011269373, 8589.355798315151, 5085.593152547787, 5092.745362731848, 5099.719515426687, 5104.004719079818, 5123.052235336475, 3946.5264168299987, 4690.530946905282, 4690.835820746258, 4737.0052289019, 4789.018251258483, 3153.8423078845967, 3533.363994481018, 3625.2598752598374, 3627.891163265106, 3719.460678201646, 1058.9099926506333, 1093.578972095733, 1100.2683215661496, 1109.7724966381872, 1119.8824123467082, 695.401954319285, 750.2235186581862, 770.2720928722416, 816.3795515408333, 842.877782721502, 5267.683938963674, 5553.611247212656, 5666.121751128499, 5693.117566777156, 5717.628354156966, 959.2805395953035, 968.8159249742527, 970.6408628807386, 988.2143695761742, 1015.3907655406845, 3674.7243956703355, 3695.667389934919, 3744.88237547317, 3836.07934095815, 3850.422436634471, 8093.4739241950265, 9392.389494682824, 9712.562934667956, 9798.069183427562, 9818.950905245429, 9718.028197180367, 9754.561044753003, 9758.756031099138, 9768.26737994639, 9779.168770654433, 7623.0, 7792.025996750406, 7792.454528182972, 7798.337141342936, 7803.765887023284, 4581.63346932238, 5181.740912954262, 5321.042212401805, 5519.613627046228, 5549.972250138723, 8754.912450875665, 8907.866382004146, 9007.369484136048, 9086.591103400231, 9154.176124148858, 8998.280137588847, 9133.680321188727, 9209.98690144097, 9239.39943903644, 9266.305027122859, 7068.187158476635, 7087.433005359456, 7229.710811567478, 7232.566046037255, 7243.601601911945, 7693.346065584488, 7715.035620547432, 7726.304632583095, 7780.727674531364, 7795.766127016199, 6319.873602528016, 6948.409385202314, 6954.547954382949, 6999.451453647874, 7039.260877607778, 6906.620135892575, 6911.205211400753, 6945.0971373721095, 6993.020977063114, 7006.75476358325, 6318.494520438262, 6342.556021078481, 6394.744210231539, 6400.711967961546, 6406.935930640634, 4841.176999910094, 4901.779419926041, 4929.433115191656, 4976.377952755905, 5123.974380128076, 4724.196886529367, 4962.702237865739, 4979.203327467588, 5016.021875734197, 5069.670471419346], "value": 4303.464623425097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.59997602972305, 370.36852167055173, 377.6884070641721, 379.4270651316515, 383.2220592197838, 4737.384140061788, 4781.952180478194, 4833.519865696701, 5110.540051395374, 5421.783128674853, 4872.756362181885, 5271.578273738101, 5318.734063296839, 5383.327084114486, 5486.780528778844, 3442.6901578857896, 3665.2486240320804, 4124.608162224591, 4254.936175957363, 4314.266574682311, 3847.230553889219, 3989.501312335958, 3999.6800255979597, 4325.437693099892, 4325.589069038437, 3668.284684486526, 4203.288878673254, 4217.683673724468, 4234.386014027964, 4256.574342565734, 3850.114473671057, 4251.893702657428, 4273.67947403946, 4281.657467402601, 4317.611414972667, 3147.8740850365957, 3807.9238415231816, 3815.9809200954096, 3841.9039524011846, 3863.961360386388, 5016.573591244749, 5300.0459917214885, 5360.9463905360835, 5424.484673955968, 5450.348266661307, 4884.55638774663, 4930.28510865926, 4975.0, 4978.178600530908, 4985.332846926913, 5131.742723032881, 5549.667019978774, 5700.458456446631, 5813.651180929158, 5849.883002339972, 3938.4879965604573, 4173.4157217989505, 4192.538820729717, 4203.97898010511, 4216.831326746927, 5078.958813443235, 5299.629025968184, 5488.588355873326, 5962.493188079018, 6044.639956009906, 4049.7772622505454, 4252.255855225323, 4295.22685916538, 4298.376735373354, 4300.333448315516, 3710.777353358807, 3785.3943369060817, 4032.697547683935, 4246.766427846436, 4373.671974601918, 4017.337139371971, 4186.706930514895, 4962.801487940515, 4968.527989840959, 4982.750862456863, 5051.494850514992, 5249.133892907627, 5731.799387021434, 5894.32215295238, 5924.940750592526, 1213.5934461955167, 1222.7554489102215, 1391.942123985758, 1409.921410121258, 1412.7810189420657, 936.9859452108087, 947.0860619502075, 1002.1331548210891, 1007.536533194724, 1016.6086056868158, 4312.525622181557, 4601.792919318638, 5062.924056139161, 5169.25045868347, 5238.97380513095, 4867.70793752376, 5080.949190508121, 5304.602154838325, 5327.813526526553, 5723.370429252779, 4403.801828917706, 4543.841675204251, 4836.903261934812, 5163.514627626338, 5232.738363081907, 2696.7168447313143, 2784.123001254639, 2918.7956843020784, 2956.600858884066, 2962.4519463899246, 5034.86715489012, 5419.888922770901, 5656.321241451053, 5759.337676167294, 5870.941290587125, 4836.685615434985, 5171.939752350759, 5186.6369354144845, 5377.489138531833, 5764.682942438206, 4940.827071052496, 5787.710614469344, 5810.675729708066, 5816.389279125716, 5915.319738230072, 5031.993601279832, 5296.973515132554, 5557.360903495988, 5683.403242659461, 5693.605066758671, 5108.795648174031, 5123.948760512496, 5365.283109404953, 5589.944100558942, 5589.959664722266, 4875.902481950412, 5201.817936372209, 5736.6558006519745, 5858.740370820124, 5869.207656966426, 3512.631173726722, 3715.64701353371, 3796.3735983563506, 3838.481804956295, 4104.988555607671, 5460.34475862899, 5545.833624991256, 5610.5231055360755, 5618.606697531133, 5649.4633009864, 4689.624830013522, 4911.926321105258, 5023.518062171741, 5586.832395028156, 5605.0, 4284.8500302489265, 5252.185911183773, 5564.276644036249, 5681.17622944679, 5739.2252045973255, 4503.729776213438, 4934.457209706858, 5015.573676237561, 5324.733763311897, 5687.744051517609, 5779.653220806765, 5806.361300257053, 5819.650820950587, 5871.412858714094, 5880.235569375954, 5191.454897235736, 5356.580506165947, 5706.2011318414625, 5742.109972954198, 5770.50950669181], "value": 4149.176573564576, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.5459451356618, 722.4112348436021, 768.2970082374627, 821.8150916043891, 822.2258545452745, 12287.569935052265, 15479.148646824435, 15674.686506269896, 15715.607109822246, 16761.748573771372, 16433.42483013095, 17029.0, 17058.976461412323, 17063.0, 17085.743713844302, 694.3230350408362, 856.0497847389408, 882.5146169606722, 884.933629977751, 885.0264708820279, 711.4948386645488, 852.9402941794077, 876.4478378621492, 888.8089060851916, 892.5314210039717, 924.0805398628374, 928.7585227840742, 929.3308817651314, 930.1163894300408, 932.0260327957267, 912.083356226991, 921.3826736086771, 921.5208091792298, 922.7831459606954, 925.5881132895864, 656.0749343425747, 682.7917485167022, 689.6448329110513, 690.5718454558156, 691.6921969723475, 15301.693966120616, 15739.291731872094, 15824.050556966504, 15851.207439628088, 16098.919505402398, 13992.790108148385, 14198.219097949712, 14218.28908554568, 14224.502142424966, 14225.715485690333, 11596.536138554447, 11600.709982250426, 11701.590444334493, 11703.531858725726, 11760.895024045085, 3691.187938653492, 3691.630836916314, 3718.200586873808, 3750.3811871041244, 3792.677622402099, 104414.0, 104779.0, 107750.46124769404, 110019.0, 110837.9999999992, 10544.103751181236, 10589.84115238272, 10601.681949541526, 10790.460476976119, 10916.0, 22026.88986555057, 22047.448813779785, 22058.89705514718, 22107.557848842935, 22127.778722212894, 6736.46108311334, 7608.80977975555, 7613.961930190314, 7659.923400765921, 7750.186230445835, 4108.732932359387, 4161.646260067868, 4195.685323600742, 4197.580241975778, 4200.222958752641, 3513.98094552582, 3606.8376923038527, 3608.6391360863704, 3610.891673249807, 3612.9638703612627, 631.4853394483481, 635.7171058878847, 646.2858541311783, 655.8557117434157, 658.5719282466339, 600.4145957691217, 654.5221987948825, 665.6505334699322, 667.0361327881166, 699.762080892499, 8308.916910830936, 10132.34139780912, 10208.693739187836, 10399.064084232456, 10417.0, 700.4536461559991, 702.8278071872423, 710.298834291868, 712.5866997141749, 721.7798571435762, 4821.879453013633, 4952.925706114411, 4985.401751789808, 4993.375828021497, 4995.375578052744, 73832.63083684548, 84418.0, 99254.00745992592, 103685.0, 108330.45834770934, 77337.0, 91397.0, 93798.0, 94545.0, 95348.0, 54753.17870232029, 59907.70046149829, 61288.69355653193, 84583.57708211543, 85825.57087214524, 9591.136797688241, 10770.461476925966, 11010.944945275221, 11027.83458248143, 11161.0, 37362.8131859339, 46153.538464614925, 49157.0, 49398.0, 50275.74862125812, 56751.0, 58036.419635803104, 66212.0, 66644.66710665857, 66792.6660366695, 13147.211167329991, 13296.002799790247, 13348.666283342995, 13486.05597608158, 13665.931670341584, 14259.548671597004, 14345.282735862957, 14923.104613722777, 15081.698366032399, 15143.0, 13695.0, 13900.304984750519, 13961.860381396462, 14001.229932353412, 14137.787933180805, 11282.717932051764, 12460.937695311466, 13972.30138493051, 13986.230757308445, 14398.568042958728, 12849.871501284868, 13671.0, 13672.0, 13672.042956992915, 13882.51411200603, 11104.61133860311, 11250.0, 11349.886501134883, 11471.655850324503, 11641.0, 8617.612207450806, 10514.631987880388, 11118.833217501582, 11146.665600032013, 11251.437428128724], "value": 7528.923357258378, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 116.0, 116.0, 117.0, 116.0, 119.0, 117.0, 117.0, 116.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 111.0, 148.0, 111.0, 111.0, 125.0, 166.0, 169.0, 111.0], "value": 130.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 205.0, 226.0, 200.0, 198.0, 197.0, 195.0, 197.0, 205.0, 190.0], "value": 200.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 58.0, 86.0, 60.0, 60.0, 58.0, 59.0, 75.0, 83.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 88.0, 72.0, 92.0, 65.0, 87.0, 103.0, 105.0, 114.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 112.0, 85.0, 108.0, 109.0, 86.0, 135.0, 107.0, 106.0, 131.0], "value": 106.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 120.0, 151.0, 94.0, 151.0, 95.0, 95.0, 105.0, 97.0], "value": 111.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 89.0, 94.0, 92.0, 92.0, 96.0, 91.0, 94.0], "value": 92.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 66.0, 64.0, 62.0, 64.0, 65.0, 64.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 74.0, 80.0, 77.0, 71.0, 70.0, 69.0, 76.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 87.0, 90.0, 88.0, 90.0, 86.0, 89.0, 87.0, 89.0, 89.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 141.0, 149.0, 138.0, 154.0, 148.0, 151.0, 161.0, 204.0, 162.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 173.0, 226.0, 202.0, 174.0, 175.0, 172.0, 171.0, 175.0], "value": 181.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 82.0, 94.0, 88.0, 91.0, 77.0, 67.0, 66.0, 70.0], "value": 77.4, "unit": "score"}]}]} 09:39:09 INFO - Return code: 0 09:39:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:39:09 ERROR - # TBPL SUCCESS # 09:39:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:09 INFO - 2017-01-31 09:39:09.318 screenresolution[2006:19415] starting screenresolution argv=screenresolution get 09:39:09 INFO - 2017-01-31 09:39:09.322 screenresolution[2006:19415] Display 0: 1600x1200x32@60 09:39:09 INFO - 2017-01-31 09:39:09.328 screenresolution[2007:19418] starting screenresolution argv=screenresolution list 09:39:09 INFO - Available Modes on Display 0 09:39:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:09 INFO - Intel Iris: 09:39:09 INFO - Chipset Model: Intel Iris 09:39:09 INFO - Type: GPU 09:39:09 INFO - Bus: Built-In 09:39:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:09 INFO - Vendor: Intel (0x8086) 09:39:09 INFO - Device ID: 0x0a2e 09:39:09 INFO - Revision ID: 0x0009 09:39:09 INFO - Displays: 09:39:09 INFO - Display: 09:39:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:09 INFO - Display Serial Number: 1600x1200 60 09:39:09 INFO - Main Display: Yes 09:39:09 INFO - Mirror: Off 09:39:09 INFO - Online: Yes 09:39:09 INFO - Rotation: Supported 09:39:09 INFO - Return code: 0 09:39:09 INFO - Running post-action listener: _resource_record_post_action 09:39:09 INFO - [mozharness: 2017-01-31 17:39:09.563661Z] Finished run-tests step (success) 09:39:09 INFO - Running post-run listener: _resource_record_post_run 09:39:09 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 7242240; Write bytes: 556679680; Read time: 63; Write time: 1934 09:39:09 INFO - TinderboxPrint: CPU usage
54.4% 09:39:09 INFO - TinderboxPrint: I/O read bytes / time
7,242,240 / 63 09:39:09 INFO - TinderboxPrint: I/O write bytes / time
556,679,680 / 1,934 09:39:09 INFO - TinderboxPrint: CPU idle
2,191.4 (45.6%) 09:39:09 INFO - TinderboxPrint: CPU system
964.6 (20.1%) 09:39:09 INFO - TinderboxPrint: CPU user
1,645.6 (34.3%) 09:39:09 INFO - TinderboxPrint: Swap in / out
267,153,408 / 0 09:39:09 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 242199040; Write bytes: 238426112; Read time: 17839; Write time: 544 09:39:09 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 7238144; Write bytes: 298981888; Read time: 63; Write time: 1348 09:39:10 INFO - Running post-run listener: _upload_blobber_files 09:39:10 INFO - Blob upload gear active. 09:39:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:39:10 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 09:39:10 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'] 09:39:10 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 09:39:10 INFO - (blobuploader) - INFO - Open directory for files ... 09:39:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:39:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:39:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:11 INFO - (blobuploader) - INFO - Done attempting. 09:39:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:39:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:39:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:12 INFO - (blobuploader) - INFO - Done attempting. 09:39:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:39:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:39:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:13 INFO - (blobuploader) - INFO - Done attempting. 09:39:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:39:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:39:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:16 INFO - (blobuploader) - INFO - Done attempting. 09:39:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:16 INFO - Return code: 0 09:39:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9414858d2391f557d0121fcb37b0ffc0e7fc6ac4a5172b74c35cdda50ae1bdde1ce67b2d5b76fde6ba3288483bd41bd37bda2c8d38879592ec51f5a21e9cf9d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcfe8e0a6ba5f65cb664b84f872cac6310afab00da9b1628df742f716c77e555a8f83a7f5b5778648a2662d694745abb92342fd6f6ea3fe1c00b292e75710681", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117c90b84817095997bf538912aa8ae159434d169181a6c2a3a9b5cd4c180baa96680ad8c690e24a8fdca593b34ed1b7ee0abbeb516be8e0e5f45bcfe8fbd4ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9d47195587ffdb5fdfee3dc922db4550a66ab167fd7a8491e099638173e4cb0eeebd9400e2a3ebc2fdb5c17d34738ae0f9cc061bd836812211112fd74158ba"} 09:39:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:16 INFO - Contents: 09:39:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9414858d2391f557d0121fcb37b0ffc0e7fc6ac4a5172b74c35cdda50ae1bdde1ce67b2d5b76fde6ba3288483bd41bd37bda2c8d38879592ec51f5a21e9cf9d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcfe8e0a6ba5f65cb664b84f872cac6310afab00da9b1628df742f716c77e555a8f83a7f5b5778648a2662d694745abb92342fd6f6ea3fe1c00b292e75710681", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117c90b84817095997bf538912aa8ae159434d169181a6c2a3a9b5cd4c180baa96680ad8c690e24a8fdca593b34ed1b7ee0abbeb516be8e0e5f45bcfe8fbd4ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9d47195587ffdb5fdfee3dc922db4550a66ab167fd7a8491e099638173e4cb0eeebd9400e2a3ebc2fdb5c17d34738ae0f9cc061bd836812211112fd74158ba"} 09:39:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:16 INFO - Copying logs to upload dir... 09:39:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.165847 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-31 09:39:16.200394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 09:39:16.204610) ========= 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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/9414858d2391f557d0121fcb37b0ffc0e7fc6ac4a5172b74c35cdda50ae1bdde1ce67b2d5b76fde6ba3288483bd41bd37bda2c8d38879592ec51f5a21e9cf9d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcfe8e0a6ba5f65cb664b84f872cac6310afab00da9b1628df742f716c77e555a8f83a7f5b5778648a2662d694745abb92342fd6f6ea3fe1c00b292e75710681", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117c90b84817095997bf538912aa8ae159434d169181a6c2a3a9b5cd4c180baa96680ad8c690e24a8fdca593b34ed1b7ee0abbeb516be8e0e5f45bcfe8fbd4ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9d47195587ffdb5fdfee3dc922db4550a66ab167fd7a8491e099638173e4cb0eeebd9400e2a3ebc2fdb5c17d34738ae0f9cc061bd836812211112fd74158ba"} build_url:https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/OYdZRvJ6Q8S20b82Xrt3Eg/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9414858d2391f557d0121fcb37b0ffc0e7fc6ac4a5172b74c35cdda50ae1bdde1ce67b2d5b76fde6ba3288483bd41bd37bda2c8d38879592ec51f5a21e9cf9d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fcfe8e0a6ba5f65cb664b84f872cac6310afab00da9b1628df742f716c77e555a8f83a7f5b5778648a2662d694745abb92342fd6f6ea3fe1c00b292e75710681", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/117c90b84817095997bf538912aa8ae159434d169181a6c2a3a9b5cd4c180baa96680ad8c690e24a8fdca593b34ed1b7ee0abbeb516be8e0e5f45bcfe8fbd4ca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba9d47195587ffdb5fdfee3dc922db4550a66ab167fd7a8491e099638173e4cb0eeebd9400e2a3ebc2fdb5c17d34738ae0f9cc061bd836812211112fd74158ba"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-31 09:39:16.244194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:39:16.244525) ========= 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.4DfnVQb5y1/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.4Kj9KR6xRO/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005086 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-31 09:39:16.292824) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 09:39:16.293140) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-31 09:39:16.296515) ========= ========= Total master_lag: 0.21 =========