builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0247 starttime: 1472972315.0 results: success (0) buildid: 20160903215151 builduid: 618e412483274c29adc0f3ea1e7616d4 revision: cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:35.003538) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:35.004066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:35.004776) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:35.455873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:35.456134) ========= 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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-03 23:58:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-09-03 23:58:36 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.554480 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:36.037832) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:36.038679) ========= 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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.035698 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:36.097975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-03 23:58:36.098282) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd --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 cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nuoY8uDaE6/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.YoMyBE9F2X/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-03 23:58:36,177 truncating revision to first 12 chars 2016-09-03 23:58:36,178 Setting DEBUG logging. 2016-09-03 23:58:36,178 attempt 1/10 2016-09-03 23:58:36,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cf087a62374d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 23:58:37,946 unpacking tar archive at: mozilla-central-cf087a62374d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.024604 ========= master_lag: 2.94 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-03 23:58:41.063975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:41.064263) ========= script_repo_revision: cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:41.064656) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:41.064926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 23:58:42.057393) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-03 23:58:42.057693) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nuoY8uDaE6/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.YoMyBE9F2X/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:58:42 INFO - MultiFileLogger online at 20160903 23:58:42 in /builds/slave/test 23:58:42 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 23:58:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:58:42 INFO - {'append_to_log': False, 23:58:42 INFO - 'base_work_dir': '/builds/slave/test', 23:58:42 INFO - 'blob_upload_branch': 'Firefox', 23:58:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:58:42 INFO - 'branch': 'Firefox', 23:58:42 INFO - 'buildbot_json_path': 'buildprops.json', 23:58:42 INFO - 'config_files': ('talos/mac_config.py',), 23:58:42 INFO - 'default_actions': ('clobber', 23:58:42 INFO - 'read-buildbot-config', 23:58:42 INFO - 'download-and-extract', 23:58:42 INFO - 'populate-webroot', 23:58:42 INFO - 'create-virtualenv', 23:58:42 INFO - 'install', 23:58:42 INFO - 'run-tests'), 23:58:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:58:42 INFO - 'download_minidump_stackwalk': True, 23:58:42 INFO - 'download_symbols': 'ondemand', 23:58:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:58:42 INFO - 'tooltool.py': '/tools/tooltool.py', 23:58:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:58:42 INFO - '/tools/misc-python/virtualenv.py')}, 23:58:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:58:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:58:42 INFO - 'installer_path': 'installer.exe', 23:58:42 INFO - 'log_level': 'info', 23:58:42 INFO - 'log_name': 'talos', 23:58:42 INFO - 'log_to_console': True, 23:58:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:58:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:58:42 INFO - 'opt_config_files': (), 23:58:42 INFO - 'pip_index': False, 23:58:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:58:42 INFO - 'cmd': ('bash', 23:58:42 INFO - '-c', 23:58:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:58:42 INFO - 'enabled': True, 23:58:42 INFO - 'halt_on_failure': False, 23:58:42 INFO - 'name': 'check_screen_resolution'},), 23:58:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:58:42 INFO - 'cmd': ('bash', 23:58:42 INFO - '-c', 23:58:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:58:42 INFO - 'enabled': True, 23:58:42 INFO - 'halt_on_failure': False, 23:58:42 INFO - 'name': 'check_screen_resolution'},), 23:58:42 INFO - 'run_cmd_checks_enabled': True, 23:58:42 INFO - 'sps_profile': False, 23:58:42 INFO - 'sps_profile_interval': 0, 23:58:42 INFO - 'suite': 'dromaeojs-e10s', 23:58:42 INFO - 'system_bits': '32', 23:58:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:58:42 INFO - 'title': 't-yosemite-r7-0247', 23:58:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:58:42 INFO - 'use_talos_json': True, 23:58:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:58:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:58:42 INFO - 'work_dir': 'build'} 23:58:42 INFO - [mozharness: 2016-09-04 06:58:42.284039Z] Running clobber step. 23:58:42 INFO - Running pre-action listener: _resource_record_pre_action 23:58:42 INFO - Running main action method: clobber 23:58:42 INFO - rmtree: /builds/slave/test/build 23:58:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:58:44 INFO - Running post-action listener: _resource_record_post_action 23:58:44 INFO - [mozharness: 2016-09-04 06:58:44.448850Z] Finished clobber step (success) 23:58:44 INFO - [mozharness: 2016-09-04 06:58:44.449124Z] Running read-buildbot-config step. 23:58:44 INFO - Running pre-action listener: _resource_record_pre_action 23:58:44 INFO - Running main action method: read_buildbot_config 23:58:44 INFO - Using buildbot properties: 23:58:44 INFO - { 23:58:44 INFO - "project": "", 23:58:44 INFO - "product": "firefox", 23:58:44 INFO - "script_repo_revision": "production", 23:58:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 23:58:44 INFO - "repository": "", 23:58:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 23:58:44 INFO - "buildid": "20160903215151", 23:58:44 INFO - "pgo_build": "False", 23:58:44 INFO - "basedir": "/builds/slave/test", 23:58:44 INFO - "buildnumber": 26, 23:58:44 INFO - "slavename": "t-yosemite-r7-0247", 23:58:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:58:44 INFO - "platform": "yosemite_r7", 23:58:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 23:58:44 INFO - "branch": "mozilla-central", 23:58:44 INFO - "repo_path": "mozilla-central", 23:58:44 INFO - "revision": "cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd", 23:58:44 INFO - "stage_platform": "macosx64", 23:58:44 INFO - "builduid": "618e412483274c29adc0f3ea1e7616d4", 23:58:44 INFO - "slavebuilddir": "test" 23:58:44 INFO - } 23:58:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:58:44 INFO - Running post-action listener: _resource_record_post_action 23:58:44 INFO - [mozharness: 2016-09-04 06:58:44.455309Z] Finished read-buildbot-config step (success) 23:58:44 INFO - [mozharness: 2016-09-04 06:58:44.455524Z] Running download-and-extract step. 23:58:44 INFO - Running pre-action listener: _resource_record_pre_action 23:58:44 INFO - Running main action method: download_and_extract 23:58:44 INFO - mkdir: /builds/slave/test/build/tests 23:58: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')]} 23:58:44 INFO - trying https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:58:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:58:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 23:58:45 INFO - Downloaded 1199 bytes. 23:58:45 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:58:45 INFO - Using the following test package requirements: 23:58:45 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:58:45 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:58:45 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:58:45 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'jsshell-mac.zip'], 23:58:45 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:58:45 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:58:45 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:58:45 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:58:45 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:58:45 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:58:45 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:58:45 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:58:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:58:45 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 23:58:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:58:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:58:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 23:59:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:59:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 23:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:01 INFO - trying https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:59:01 INFO - Downloading https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:59:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 23:59:07 INFO - Downloaded 111489217 bytes. 23:59:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:59:07 INFO - mkdir: /builds/slave/test/properties 23:59:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:59:07 INFO - Writing to file /builds/slave/test/properties/build_url 23:59:07 INFO - Contents: 23:59:07 INFO - build_url:https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:59:08 INFO - Running post-action listener: _resource_record_post_action 23:59:08 INFO - Running post-action listener: set_extra_try_arguments 23:59:08 INFO - [mozharness: 2016-09-04 06:59:08.673607Z] Finished download-and-extract step (success) 23:59:08 INFO - [mozharness: 2016-09-04 06:59:08.673738Z] Running populate-webroot step. 23:59:08 INFO - Running pre-action listener: _resource_record_pre_action 23:59:08 INFO - Running main action method: populate_webroot 23:59:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:59:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:59:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:59:08 INFO - '--tppagecycles', 23:59:08 INFO - '7'], 23:59:08 INFO - 'tests': ['tsvgm']}}, 23:59:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:59:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:59:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:59:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:59:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:59:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:59:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['damp', 'tps']}, 23:59:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'tests': ['damp', 'tps']}, 23:59:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['dromaeo_dom']}, 23:59:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:59:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['basic_compositor_video']}, 23:59:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:59:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['a11yr', 23:59:08 INFO - 'ts_paint', 23:59:08 INFO - 'tpaint', 23:59:08 INFO - 'sessionrestore', 23:59:08 INFO - 'sessionrestore_no_auto_restore', 23:59:08 INFO - 'tabpaint']}, 23:59:08 INFO - 'other-e10s': {'tests': ['a11yr', 23:59:08 INFO - 'ts_paint', 23:59:08 INFO - 'tpaint', 23:59:08 INFO - 'sessionrestore', 23:59:08 INFO - 'sessionrestore_no_auto_restore', 23:59:08 INFO - 'tabpaint']}, 23:59:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:59:08 INFO - 'ts_paint', 23:59:08 INFO - 'tpaint', 23:59:08 INFO - 'sessionrestore', 23:59:08 INFO - 'sessionrestore_no_auto_restore', 23:59:08 INFO - 'tabpaint']}, 23:59:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:59:08 INFO - 'ts_paint', 23:59:08 INFO - 'tpaint', 23:59:08 INFO - 'sessionrestore', 23:59:08 INFO - 'sessionrestore_no_auto_restore', 23:59:08 INFO - 'tabpaint']}, 23:59:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['a11yr', 23:59:08 INFO - 'ts_paint', 23:59:08 INFO - 'tpaint', 23:59:08 INFO - 'sessionrestore', 23:59:08 INFO - 'sessionrestore_no_auto_restore', 23:59:08 INFO - 'tabpaint']}, 23:59:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['a11yr', 23:59:08 INFO - 'ts_paint', 23:59:08 INFO - 'tpaint', 23:59:08 INFO - 'sessionrestore', 23:59:08 INFO - 'sessionrestore_no_auto_restore', 23:59:08 INFO - 'tabpaint']}, 23:59:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['tsvgx', 23:59:08 INFO - 'tsvgr_opacity', 23:59:08 INFO - 'tart', 23:59:08 INFO - 'tscrollx', 23:59:08 INFO - 'cart']}, 23:59:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:59:08 INFO - 'tsvgr_opacity', 23:59:08 INFO - 'tart', 23:59:08 INFO - 'tscrollx', 23:59:08 INFO - 'cart']}, 23:59:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'talos_options': ['--disable-e10s'], 23:59:08 INFO - 'tests': ['tp5o']}, 23:59:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'tests': ['tp5o']}, 23:59:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'talos_options': ['--disable-e10s', 23:59:08 INFO - '--xperf_path', 23:59:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:59:08 INFO - 'tests': ['tp5n']}, 23:59:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:59:08 INFO - 'talos_options': ['--xperf_path', 23:59:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:59:08 INFO - 'tests': ['tp5n']}}, 23:59:08 INFO - 'talos.zip': {'path': '', 23:59:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:59:08 INFO - Running post-action listener: _resource_record_post_action 23:59:08 INFO - [mozharness: 2016-09-04 06:59:08.692668Z] Finished populate-webroot step (success) 23:59:08 INFO - [mozharness: 2016-09-04 06:59:08.692783Z] Running create-virtualenv step. 23:59:08 INFO - Running pre-action listener: _resource_record_pre_action 23:59:08 INFO - Running main action method: create_virtualenv 23:59:08 INFO - Creating virtualenv /builds/slave/test/build/venv 23:59:08 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 23:59:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:59:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:59:08 INFO - Using real prefix '/tools/python27' 23:59:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:59:09 INFO - Installing distribute.............................................................................................................................................................................................done. 23:59:12 INFO - Installing pip.................done. 23:59:12 INFO - Return code: 0 23:59:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:59:12 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')]} 23:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:12 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')]} 23:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:12 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:12 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 23:59:12 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 23:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:12 INFO - 'HOME': '/Users/cltbld', 23:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:12 INFO - 'LOGNAME': 'cltbld', 23:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:12 INFO - 'MOZ_NO_REMOTE': '1', 23:59:12 INFO - 'NO_EM_RESTART': '1', 23:59:12 INFO - 'PAGER': '/bin/cat', 23:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:12 INFO - 'PWD': '/builds/slave/test', 23:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:12 INFO - 'SHELL': '/bin/bash', 23:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:12 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:12 INFO - 'USER': 'cltbld', 23:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:12 INFO - 'XPC_FLAGS': '0x0', 23:59:12 INFO - 'XPC_SERVICE_NAME': '0', 23:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:12 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 23:59:12 INFO - Cleaning up... 23:59:12 INFO - Return code: 0 23:59:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:59:12 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')]} 23:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:12 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')]} 23:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:12 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:12 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 23:59:12 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 23:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:12 INFO - 'HOME': '/Users/cltbld', 23:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:12 INFO - 'LOGNAME': 'cltbld', 23:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:12 INFO - 'MOZ_NO_REMOTE': '1', 23:59:12 INFO - 'NO_EM_RESTART': '1', 23:59:12 INFO - 'PAGER': '/bin/cat', 23:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:12 INFO - 'PWD': '/builds/slave/test', 23:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:12 INFO - 'SHELL': '/bin/bash', 23:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:12 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:12 INFO - 'USER': 'cltbld', 23:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:12 INFO - 'XPC_FLAGS': '0x0', 23:59:12 INFO - 'XPC_SERVICE_NAME': '0', 23:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:12 INFO - Downloading/unpacking psutil>=3.1.1 23:59:12 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 23:59:12 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 23:59:12 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 23:59:12 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 23:59:12 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 23:59:12 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 23:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:59:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:59:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:59:16 INFO - Installing collected packages: psutil 23:59:16 INFO - Running setup.py install for psutil 23:59:16 INFO - building 'psutil._psutil_osx' extension 23:59:16 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 23:59:16 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 23:59:16 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 23:59:17 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 23:59:17 INFO - building 'psutil._psutil_posix' extension 23:59:17 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 23:59:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:59:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:59:17 INFO - ^ 23:59:17 INFO - 1 warning generated. 23:59:17 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 23:59:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:59:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:59:17 INFO - Successfully installed psutil 23:59:17 INFO - Cleaning up... 23:59:17 INFO - Return code: 0 23:59:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:17 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:17 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 23:59:17 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 23:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:17 INFO - 'HOME': '/Users/cltbld', 23:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:17 INFO - 'LOGNAME': 'cltbld', 23:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:17 INFO - 'MOZ_NO_REMOTE': '1', 23:59:17 INFO - 'NO_EM_RESTART': '1', 23:59:17 INFO - 'PAGER': '/bin/cat', 23:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:17 INFO - 'PWD': '/builds/slave/test', 23:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:17 INFO - 'SHELL': '/bin/bash', 23:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:17 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:17 INFO - 'USER': 'cltbld', 23:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:17 INFO - 'XPC_FLAGS': '0x0', 23:59:17 INFO - 'XPC_SERVICE_NAME': '0', 23:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:59:17 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 23:59:17 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 23:59:17 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 23:59:17 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 23:59:17 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 23:59:17 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 23:59:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:59:20 INFO - Installing collected packages: mozsystemmonitor 23:59:20 INFO - Running setup.py install for mozsystemmonitor 23:59:20 INFO - Successfully installed mozsystemmonitor 23:59:20 INFO - Cleaning up... 23:59:20 INFO - Return code: 0 23:59:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:59:20 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')]} 23:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:20 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')]} 23:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:20 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:20 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 23:59:20 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 23:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:20 INFO - 'HOME': '/Users/cltbld', 23:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:20 INFO - 'LOGNAME': 'cltbld', 23:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:20 INFO - 'MOZ_NO_REMOTE': '1', 23:59:20 INFO - 'NO_EM_RESTART': '1', 23:59:20 INFO - 'PAGER': '/bin/cat', 23:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:20 INFO - 'PWD': '/builds/slave/test', 23:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:20 INFO - 'SHELL': '/bin/bash', 23:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:20 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:20 INFO - 'USER': 'cltbld', 23:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:20 INFO - 'XPC_FLAGS': '0x0', 23:59:20 INFO - 'XPC_SERVICE_NAME': '0', 23:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:20 INFO - Downloading/unpacking jsonschema==2.5.1 23:59:20 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 23:59:20 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 23:59:20 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 23:59:20 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 23:59:20 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 23:59:20 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 23:59:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:59:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:59:23 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 23:59:23 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 23:59:23 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 23:59:23 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 23:59:23 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 23:59:23 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 23:59:23 INFO - Downloading functools32-3.2.3-2.tar.gz 23:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:59:23 INFO - warning: no files found matching '*.txt' 23:59:23 INFO - no previously-included directories found matching 'build' 23:59:23 INFO - no previously-included directories found matching 'dist' 23:59:23 INFO - no previously-included directories found matching '.git*' 23:59:23 INFO - Installing collected packages: jsonschema, functools32 23:59:23 INFO - Running setup.py install for functools32 23:59:23 INFO - warning: no files found matching '*.txt' 23:59:23 INFO - no previously-included directories found matching 'build' 23:59:23 INFO - no previously-included directories found matching 'dist' 23:59:23 INFO - no previously-included directories found matching '.git*' 23:59:23 INFO - Successfully installed jsonschema functools32 23:59:23 INFO - Cleaning up... 23:59:23 INFO - Return code: 0 23:59:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:59:23 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')]} 23:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:23 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')]} 23:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:23 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:23 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 23:59:23 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 23:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:23 INFO - 'HOME': '/Users/cltbld', 23:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:23 INFO - 'LOGNAME': 'cltbld', 23:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:23 INFO - 'MOZ_NO_REMOTE': '1', 23:59:23 INFO - 'NO_EM_RESTART': '1', 23:59:23 INFO - 'PAGER': '/bin/cat', 23:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:23 INFO - 'PWD': '/builds/slave/test', 23:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:23 INFO - 'SHELL': '/bin/bash', 23:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:23 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:23 INFO - 'USER': 'cltbld', 23:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:23 INFO - 'XPC_FLAGS': '0x0', 23:59:23 INFO - 'XPC_SERVICE_NAME': '0', 23:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:59:24 INFO - Cleaning up... 23:59:24 INFO - Return code: 0 23:59:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:59:24 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')]} 23:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:24 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')]} 23:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:24 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:24 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 23:59:24 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 23:59:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:24 INFO - 'HOME': '/Users/cltbld', 23:59:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:24 INFO - 'LOGNAME': 'cltbld', 23:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:24 INFO - 'MOZ_NO_REMOTE': '1', 23:59:24 INFO - 'NO_EM_RESTART': '1', 23:59:24 INFO - 'PAGER': '/bin/cat', 23:59:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:24 INFO - 'PWD': '/builds/slave/test', 23:59:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:24 INFO - 'SHELL': '/bin/bash', 23:59:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:24 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:24 INFO - 'USER': 'cltbld', 23:59:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:24 INFO - 'XPC_FLAGS': '0x0', 23:59:24 INFO - 'XPC_SERVICE_NAME': '0', 23:59:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:24 INFO - Downloading/unpacking blobuploader==1.2.4 23:59:24 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 23:59:24 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 23:59:24 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 23:59:24 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 23:59:24 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 23:59:24 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 23:59:26 INFO - Downloading blobuploader-1.2.4.tar.gz 23:59:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:59:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:59:26 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 23:59:26 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 23:59:26 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 23:59:26 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 23:59:26 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 23:59:26 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 23:59:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:59:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:59:27 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 23:59:27 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 23:59:27 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 23:59:27 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 23:59:27 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 23:59:27 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 23:59:27 INFO - Downloading docopt-0.6.1.tar.gz 23:59:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:59:28 INFO - Installing collected packages: blobuploader, requests, docopt 23:59:28 INFO - Running setup.py install for blobuploader 23:59:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:59:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:59:28 INFO - Running setup.py install for requests 23:59:28 INFO - Running setup.py install for docopt 23:59:28 INFO - Successfully installed blobuploader requests docopt 23:59:28 INFO - Cleaning up... 23:59:28 INFO - Return code: 0 23:59:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:59:28 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')]} 23:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:28 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')]} 23:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:28 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:28 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 23:59:28 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 23:59:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:28 INFO - 'HOME': '/Users/cltbld', 23:59:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:28 INFO - 'LOGNAME': 'cltbld', 23:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:28 INFO - 'MOZ_NO_REMOTE': '1', 23:59:28 INFO - 'NO_EM_RESTART': '1', 23:59:28 INFO - 'PAGER': '/bin/cat', 23:59:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:28 INFO - 'PWD': '/builds/slave/test', 23:59:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:28 INFO - 'SHELL': '/bin/bash', 23:59:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:28 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:28 INFO - 'USER': 'cltbld', 23:59:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:28 INFO - 'XPC_FLAGS': '0x0', 23:59:28 INFO - 'XPC_SERVICE_NAME': '0', 23:59:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-z8I1Pt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-LkPnPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-VjmNQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Phy3rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-q1tp17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-grogIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-_M1C3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:59:29 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-CxB50y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-AXoCWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-yxcD5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-4Rr7jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-zvXdtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-pFpFDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-z0scfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-fEbaf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-NJ0dQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:59:30 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-MyS02L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:59:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:59:30 INFO - Running setup.py install for manifestparser 23:59:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:59:31 INFO - Running setup.py install for mozcrash 23:59:31 INFO - Running setup.py install for mozdebug 23:59:31 INFO - Running setup.py install for mozdevice 23:59:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:59:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:59:31 INFO - Running setup.py install for mozfile 23:59:31 INFO - Running setup.py install for mozhttpd 23:59:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:59:31 INFO - Running setup.py install for mozinfo 23:59:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:59:31 INFO - Running setup.py install for mozInstall 23:59:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Running setup.py install for mozleak 23:59:32 INFO - Running setup.py install for mozlog 23:59:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Running setup.py install for moznetwork 23:59:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Running setup.py install for mozprocess 23:59:32 INFO - Running setup.py install for mozprofile 23:59:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:59:32 INFO - Running setup.py install for mozrunner 23:59:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:59:33 INFO - Running setup.py install for mozscreenshot 23:59:33 INFO - Running setup.py install for moztest 23:59:33 INFO - Running setup.py install for mozversion 23:59:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:59:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:59:33 INFO - Cleaning up... 23:59:33 INFO - Return code: 0 23:59:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:33 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:33 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 23:59:33 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 23:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:33 INFO - 'HOME': '/Users/cltbld', 23:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:33 INFO - 'LOGNAME': 'cltbld', 23:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:33 INFO - 'MOZ_NO_REMOTE': '1', 23:59:33 INFO - 'NO_EM_RESTART': '1', 23:59:33 INFO - 'PAGER': '/bin/cat', 23:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:33 INFO - 'PWD': '/builds/slave/test', 23:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:33 INFO - 'SHELL': '/bin/bash', 23:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:33 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:33 INFO - 'USER': 'cltbld', 23:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:33 INFO - 'XPC_FLAGS': '0x0', 23:59:33 INFO - 'XPC_SERVICE_NAME': '0', 23:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:59:33 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-EnszEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:59:33 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)) 23:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:59:33 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-mCzkHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:59:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:59:33 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-ciNHpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-pm2_Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-FAXQ59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-5T1B4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-i5TbUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-TiFzQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-2O1xLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Ywctb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-PCPaZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:59:34 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)) 23:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:59:34 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-7GsSI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:59:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:59:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-8Pulap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:59:35 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)) 23:59:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:59:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-fsKjEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:59:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:59:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-BUZRad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:59:35 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)) 23:59:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:59:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-FvNbke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:59:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:59:35 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-KG5bSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:59:35 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)) 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:59:35 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)) 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:59:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:59:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:59:35 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 23:59:35 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 23:59:35 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 23:59:35 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 23:59:35 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 23:59:35 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 23:59:38 INFO - Downloading blessings-1.6.tar.gz 23:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:59:38 INFO - Installing collected packages: blessings 23:59:38 INFO - Running setup.py install for blessings 23:59:38 INFO - Successfully installed blessings 23:59:38 INFO - Cleaning up... 23:59:38 INFO - Return code: 0 23:59:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:59:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:59:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:59:38 INFO - Reading from file tmpfile_stdout 23:59:38 INFO - Current package versions: 23:59:38 INFO - blessings == 1.6 23:59:38 INFO - blobuploader == 1.2.4 23:59:38 INFO - docopt == 0.6.1 23:59:38 INFO - functools32 == 3.2.3-2 23:59:38 INFO - jsonschema == 2.5.1 23:59:38 INFO - manifestparser == 1.1 23:59:38 INFO - mozInstall == 1.12 23:59:38 INFO - mozcrash == 0.17 23:59:38 INFO - mozdebug == 0.1 23:59:38 INFO - mozdevice == 0.48 23:59:38 INFO - mozfile == 1.2 23:59:38 INFO - mozhttpd == 0.7 23:59:38 INFO - mozinfo == 0.9 23:59:38 INFO - mozleak == 0.1 23:59:38 INFO - mozlog == 3.3 23:59:38 INFO - moznetwork == 0.27 23:59:38 INFO - mozprocess == 0.23 23:59:38 INFO - mozprofile == 0.28 23:59:38 INFO - mozrunner == 6.12 23:59:38 INFO - mozscreenshot == 0.1 23:59:38 INFO - mozsystemmonitor == 0.3 23:59:38 INFO - moztest == 0.7 23:59:38 INFO - mozversion == 1.4 23:59:38 INFO - psutil == 3.1.1 23:59:38 INFO - requests == 1.2.3 23:59:38 INFO - wsgiref == 0.1.2 23:59:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:38 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:38 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 23:59:38 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 23:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:38 INFO - 'HOME': '/Users/cltbld', 23:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:38 INFO - 'LOGNAME': 'cltbld', 23:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:38 INFO - 'MOZ_NO_REMOTE': '1', 23:59:38 INFO - 'NO_EM_RESTART': '1', 23:59:38 INFO - 'PAGER': '/bin/cat', 23:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:38 INFO - 'PWD': '/builds/slave/test', 23:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:38 INFO - 'SHELL': '/bin/bash', 23:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:38 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:38 INFO - 'USER': 'cltbld', 23:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:38 INFO - 'XPC_FLAGS': '0x0', 23:59:38 INFO - 'XPC_SERVICE_NAME': '0', 23:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 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)) 23:59:38 INFO - Cleaning up... 23:59:38 INFO - Return code: 0 23:59:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:59:38 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 0x10a435a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4ca618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc803c8af90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, '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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 23:59:38 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 23:59:38 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 23:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 23:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:59:38 INFO - 'HOME': '/Users/cltbld', 23:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:59:38 INFO - 'LOGNAME': 'cltbld', 23:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:59:38 INFO - 'MOZ_NO_REMOTE': '1', 23:59:38 INFO - 'NO_EM_RESTART': '1', 23:59:38 INFO - 'PAGER': '/bin/cat', 23:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:59:38 INFO - 'PWD': '/builds/slave/test', 23:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:59:38 INFO - 'SHELL': '/bin/bash', 23:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 23:59:38 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 23:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:59:38 INFO - 'USER': 'cltbld', 23:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:59:38 INFO - 'XPC_FLAGS': '0x0', 23:59:38 INFO - 'XPC_SERVICE_NAME': '0', 23:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:59:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:59:39 INFO - Cleaning up... 23:59:39 INFO - Return code: 0 23:59:39 INFO - Running post-action listener: _resource_record_post_action 23:59:39 INFO - Running post-action listener: _start_resource_monitoring 23:59:39 INFO - Starting resource monitoring. 23:59:39 INFO - [mozharness: 2016-09-04 06:59:39.257997Z] Finished create-virtualenv step (success) 23:59:39 INFO - [mozharness: 2016-09-04 06:59:39.259058Z] Running install step. 23:59:39 INFO - Running pre-action listener: _resource_record_pre_action 23:59:39 INFO - Running main action method: install 23:59:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:59:39 INFO - Reading from file tmpfile_stdout 23:59:39 INFO - Detecting whether we're running mozinstall >=1.0... 23:59:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:59:39 INFO - Reading from file tmpfile_stdout 23:59:39 INFO - Output received: 23:59:39 INFO - Usage: mozinstall [options] installer 23:59:39 INFO - Options: 23:59:39 INFO - -h, --help show this help message and exit 23:59:39 INFO - -d DEST, --destination=DEST 23:59:39 INFO - Directory to install application into. [default: 23:59:39 INFO - "/builds/slave/test"] 23:59:39 INFO - --app=APP Application being installed. [default: firefox] 23:59:39 INFO - mkdir: /builds/slave/test/build/application 23:59:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 00:00:14 INFO - Reading from file tmpfile_stdout 00:00:14 INFO - Output received: 00:00:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:00:14 INFO - Running post-action listener: _resource_record_post_action 00:00:14 INFO - [mozharness: 2016-09-04 07:00:14.062366Z] Finished install step (success) 00:00:14 INFO - [mozharness: 2016-09-04 07:00:14.062523Z] Running run-tests step. 00:00:14 INFO - Running pre-action listener: _resource_record_pre_action 00:00:14 INFO - Running main action method: run_tests 00:00:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:00:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:00:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:00:14 INFO - 2016-09-04 00:00:14.076 screenresolution[1646:8051] starting screenresolution argv=screenresolution get 00:00:14 INFO - 2016-09-04 00:00:14.079 screenresolution[1646:8051] Display 0: 1600x1200x32@60 00:00:14 INFO - 2016-09-04 00:00:14.085 screenresolution[1647:8053] starting screenresolution argv=screenresolution list 00:00:14 INFO - Available Modes on Display 0 00:00:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:00:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:00:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:00:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:00:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:00:14 INFO - Intel Iris: 00:00:14 INFO - Chipset Model: Intel Iris 00:00:14 INFO - Type: GPU 00:00:14 INFO - Bus: Built-In 00:00:14 INFO - VRAM (Dynamic, Max): 1536 MB 00:00:14 INFO - Vendor: Intel (0x8086) 00:00:14 INFO - Device ID: 0x0a2e 00:00:14 INFO - Revision ID: 0x0009 00:00:14 INFO - Displays: 00:00:14 INFO - Display: 00:00:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:00:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:00:14 INFO - Display Serial Number: 1600x1200 60 00:00:14 INFO - Main Display: Yes 00:00:14 INFO - Mirror: Off 00:00:14 INFO - Online: Yes 00:00:14 INFO - Rotation: Supported 00:00:14 INFO - Return code: 0 00:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:00:14 INFO - Python 2.7.3 00:00:14 INFO - Return code: 0 00:00:14 INFO - grabbing minidump binary from tooltool 00:00: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')]} 00:00:14 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 0x10a399d30>, '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 0x10a0fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a391b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:00:14 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 00:00:14 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 00:00:14 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 00:00:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:00:14 INFO - Return code: 0 00:00:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:00:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:00:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:00:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:00:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:00:14 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/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 00:00:14 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/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0247 --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 00:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nuoY8uDaE6/Render', 00:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:00:14 INFO - 'HOME': '/Users/cltbld', 00:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:00:14 INFO - 'LOGNAME': 'cltbld', 00:00:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:00:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:00:14 INFO - 'MOZ_NO_REMOTE': '1', 00:00:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:00:14 INFO - 'NO_EM_RESTART': '1', 00:00:14 INFO - 'PAGER': '/bin/cat', 00:00:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:00:14 INFO - 'PWD': '/builds/slave/test', 00:00:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:00:14 INFO - 'SHELL': '/bin/bash', 00:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YoMyBE9F2X/Listeners', 00:00:14 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 00:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:00:14 INFO - 'USER': 'cltbld', 00:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:00:14 INFO - 'XPC_FLAGS': '0x0', 00:00:14 INFO - 'XPC_SERVICE_NAME': '0', 00:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:00:14 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/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 00:00:14 INFO - mozversion application_buildid: 20160903215151 00:00:14 INFO - mozversion application_changeset: cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd 00:00:14 INFO - mozversion application_display_name: Nightly 00:00:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:00:14 INFO - mozversion application_name: Firefox 00:00:14 INFO - mozversion application_remotingname: firefox 00:00:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 00:00:14 INFO - mozversion application_vendor: Mozilla 00:00:14 INFO - mozversion application_version: 51.0a1 00:00:14 INFO - mozversion platform_buildid: 20160903215151 00:00:14 INFO - mozversion platform_changeset: cf087a62374d4b2bbfa5b3903bfa450b0e90a8dd 00:00:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 00:00:14 INFO - mozversion platform_version: 51.0a1 00:00:14 INFO - using testdate: 1472972414 00:00:14 INFO - actual date: 1472972414 00:00:14 INFO - starting webserver on 'localhost:49276' 00:00:14 INFO - SUITE-START | Running 2 tests 00:00:14 INFO - TEST-START | dromaeo_css 00:00:14 INFO - operating with platform_type : mac_ 00:00:14 INFO - Initialising browser for dromaeo_css test... 00:00:14 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpRsYwvU/profile http://localhost:49276/getInfo.html) 00:00:17 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 00:00:17 INFO - PROCESS | 1657 | colorDepth:24 00:00:17 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 00:00:17 INFO - PROCESS | 1657 | __metrics 00:00:17 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 00:00:18 INFO - TEST-INFO | 1657: exit 0 00:00:18 INFO - Browser initialized. 00:00:18 INFO - Running cycle 1/1 for dromaeo_css test... 00:00:18 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpRsYwvU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:00:30 INFO - PROCESS | 1660 | RSS: Main: 245354496 00:00:30 INFO - PROCESS | 1660 | 00:01:20 INFO - PROCESS | 1660 | 1472972480084 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 00:03:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 00:03:33 INFO - PROCESS | 1660 | RSS: Main: 256622592 00:03:33 INFO - PROCESS | 1660 | 00:06:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 00:06:36 INFO - PROCESS | 1660 | RSS: Main: 262922240 00:06:36 INFO - PROCESS | 1660 | 00:09:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 00:09:38 INFO - PROCESS | 1660 | RSS: Main: 264454144 00:09:38 INFO - PROCESS | 1660 | 00:12:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 00:12:41 INFO - PROCESS | 1660 | RSS: Main: 268992512 00:12:41 INFO - PROCESS | 1660 | 00:15:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 00:15:44 INFO - PROCESS | 1660 | RSS: Main: 269574144 00:15:44 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 00:18:47 INFO - PROCESS | 1660 | RSS: Main: 274153472 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | __start_tp_report 00:18:47 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 00:18:47 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 00:18:47 INFO - PROCESS | 1660 | |i|pagename|runs| 00:18:47 INFO - PROCESS | 1660 | |0;dojo.html;3309.735221182304;3337.6662333766617;3372.4772660237654;3376.3416133853907;3393.1856354474935;5649.124385720214;5669.177969194464;5718.342390625075;5808.680522571268;5822.82531524055;5058.291839142523;5702.77188912444;5755.223044888939;5811.4479124483105;5815.156802263669;5163.870903227421;5213.556847667934;5257.658252213613;5316.680999140064;5322.946770532303;5224.425313215562;5388.703621300827;5407.432219616942;5412.621116521845;5417.972910135444;5149.871253218662;5205.375354957429;5219.973900130494;5263.421023687391;5274.868128296785;5242.79028838846;5263.552598029172;5267.3679158500845;5292.338457692788;5305.549028332596;5188.45521220274;5195.610329225286;5200.323957885488;5234.555062819665;5303.310569625963;4003.0592810689463;4717.693349932278;5626.465485778845;5691.743871525791;5774.451427114417;6203.255609326865;6230.7819226326865;6258.968705156491;6260.15487909131;6289.496840252815;6221.471174950135;6231.034189700604;6255.280642726099;6265.0915617235705;6282.87434251317;5778.653280803207;5807.128930660435;5863.472287494061;5929.584929054968;5949.732262048218;6287.654179020198;6296.118543403927;6302.4958003359625;6308.589941653778;6319;6073.544484163705;6122.326544080148;6128.3565225651555;6134.294556126013;6137.754489820447;5814.622049566765;6112.473085128069;6129.632222066691;6189.721462534107;6202.534809889276;6100.217864923808;6117.4620395853735;6158.230221222348;6176.536759743038;6187.041008643668;5211.478852114758;5677.318721753416;6248.718807653667;6259.374062593704;6261.8747625047245;4864.075825593126;4908.190148625508;4967.453580106186;4986;4999.075171093359;3781.4516895050065;4402.691811573223;4441.244988351988;4446.644268458515;4543.977280113579;5624.971875140598;5643.463870932255;5772.307323121168;5784.71076446176;5797.130430435468;6113.38866113394;6237.376262373817;6244.37556244381;6261.154744109578;6288;5701.258836351248;5749.108888122348;5750.884982300414;5782.248307720053;5875.030619947661;5428.45715428454;5432.239486471897;5432.809851655252;5448.618596698193;5450.427705090988;6287.937120628918;6307.842303942346;6310.873782524415;6315.494760419156;6325.7785977491485;6104.125697713769;6161.353057928853;6199.721012554356;6200.658963756856;6216.564840461305;6302.936970630235;6308.621482711052;6321.209848768904;6348.587341822767;6376.521760868046;5456.42707515721;5987.850303742442;6154.415330543591;6227.657478838707;6239.064140378798;6128.044828652092;6158.753649853956;6163.7842675506145;6205.689715514297;6261.718222680081;6102.816915492543;6160.075988601746;6173.98129308659;6174.506039516918;6181.53638477125;6043.456088952015;6098.908516372347;6151.446369826737;6161.383861613803;6166.352532983972;4709.740964246862;5110.3356563646485;5812.651240925558;6031.75872965094;6058.57589968698;6136.355682653436;6143.1706719592285;6147.231596050494;6169.136320914986;6171.321154672893;5890.764369425175;6003.729832157476;6009.609375390761;6013.849653758691;6028.668423236764 00:18:47 INFO - PROCESS | 1660 | |1;ext.html;483.45852645037553;537.569944044764;537.6773935638616;543.1228565866128;544.4854612391292;28123.859380703092;40868.59131408685;40919.38620920681;40966.18067638645;40989.3851592226;41595.58404415955;41661.166776664446;42345;42384.36423453651;42427;9646.035396460367;9781.315307928448;9813.460259685715;9847.704568862946;9881.407115573053;9640.855387169164;9646.035396460333;9658.517074146299;9659;9757.658481953133;9594.520273986273;9756.658516951908;9785.07041831025;9788.755281117958;9820.705378838646;9719.416834989923;9756.170725488306;9771.853422198672;9784.902150978542;9799.706008819747;7791.948087008238;8604.096569860138;9541.141297283279;9779.022097790164;9779.657711980117;31634;39994.400083998764;40095;40169.39745903814;40210.39684404736;3313.188268874117;3341.465365541502;3346.330733853215;3558.19940513385;3699.1676872703697;25239.873800631063;30577.388452231055;30583.541246881316;30808.845955770303;30922.84538577315;3255.609326880766;3286.1620286826706;3289.227031647561;3290.9835450822834;3344.5819272590925;483926;484703;488463.55768221285;489863;490648;61461;61909.38090619126;61934.0709889352;61974;62115.689421552604;34830.65169348274;35286.117847053516;35494.29011419809;36210.456843147374;64664.353356466774;16509.422170223985;16672.74990875138;16678.58303542397;16706.582335441468;16720.41478548245;12706.555270565486;12820.358982051046;12836.908862746584;12852.48590056406;12874.485020599259;19329.53245156866;19397.094467144605;19635;19671.409857704293;19722.211111555378;7069;7616.543007419573;7943.83650627097;7964.442489025712;8016.278534931767;839.9286060684788;927.9257659387233;981.4542096198516;995.4077323992213;1028.126092821096;29957.40085198284;30943.381132377228;31018.224544386572;31024.0692779217;31577;945.5414124149781;994.3089552760762;1071.6249312740479;1072.7264547540328;1080.7028067281403;15210.771838422432;15838.208089595475;15847.920760396124;15918.363265469252;16002.959807612468;684852;685033;690940;693859;700602;57608.71195644079;59159.40840591539;59326;59372.812543748885;59456;1371.5542448704211;1392.1090502078678;1396.211140705498;1408.457743768677;1409.0770545292849;29184.41631167407;30238.244043899078;30316.84841575778;30633.693663063976;30687.539686904267;52257.7387113062;52476.73761631167;52726.8909243626;52748;52770.94458110893;581313;591880;592209;593713;595581;26813;26912.730872691805;27207.183784486497;27320.59019114675;27321.180364589094;47020;47074.52925470701;48053;48134.03731925412;48362.51637483721;32592;39183.020424489616;39376.01559961024;40439.1912161761;40991.38512922368;38934;39123.80438097791;39339;40248.798756006036;40447;32816.91337842638;37728.43407348947;38059;38468.807655961544;38516.61483385242;28768;32474.02577922666;33060;33852.153696157795;34905.65094349024;20085.698714519003;23832.642510362704;25779.097731579306;25889;26335.868320658275 00:18:47 INFO - PROCESS | 1660 | |2;jquery.html;371.1111887030562;374.48882275693677;380.63078813550857;385.3583783001303;399.94000899865034;16119.677606447893;19141.617167656637;20146.89926550369;21791.237306694267;22036.779632203674;20391.898040509815;20514.794852051476;21005.579888402222;21073;21299.36101916941;5890.499307558863;6181.90727139091;6222.191115155046;6223.190985171921;6241.812745617639;5917.615355001911;6081.969590152065;6082.300535438437;6157.261128664567;6182.258129024523;6003.759849606033;6085.726142323584;6097.085437184435;6106.450419462248;6118.378629663142;5933.228680271559;6072.878542429149;6095.939040609582;6118.449339559461;6128.295246046717;4363.323684828857;5192.688438693691;5298.708571028592;5302.443243459458;5308.840734777963;25033;25291.873540632365;25363.239102826945;26408.075717349802;26560.335991600172;2197.2419515267206;2217.1796435319034;2234.1398561553756;2314.5486630107134;3061.0510741670155;12960.611181664564;15310.846891531164;15855.207239637972;16030.95798773076;16443.753343699853;1836.8438682711985;1885.279779303749;1896.3268039845932;1960.1179469238757;1986.2650819196845;117976;122176.99999999911;122579;123461.9999999991;123589;10718;10777;11198.216124871344;11235.325880447199;11385.37380444067;12056.156069075256;12798.68003299925;13418.530351437652;13664.590062298146;13704.52034178799;8085.716999904975;8139.145389734111;8297.626606802709;8551.273141782895;8747.868781968276;7163.212046674862;8610;8631.223189912938;8693.91306086931;8832.425892316978;5752.626079304831;6439.967800160969;6600.636964966973;6695.393105654608;6752.493562982796;1758.9182652668517;1845.6308738252135;1877.5775450523797;1893.2616279650947;1916.3101283537915;690.9930900690929;793.3256731777942;898.4788822482944;920.724796157327;927.536231884058;18161.90919045423;21890.014949327;22290.108395663992;22401.327960161223;22523.549529009655;915.4598786715869;968.2544440780559;974.2644303550916;976.321456587679;1013.1540163963173;11517.366544839946;11794.705132371759;11904.404779760976;11929.642110736691;12041.939790301167;524884;579452;582900;599657;599992;116613.25080123804;117118;117974.41012795053;119173.21240181405;121332;975.1418751498636;989.9999999999856;999.0459111548454;1002.9398236105711;1003.3327836988321;18980.525486862938;19686;19884.801151988297;19893.502662433555;19904.303349382702;50021.74989125177;51287;51620.74189629028;51773;52604;72242;72959.17602059779;73538.89691654737;74429;74837.62581187059;6991.8951215730785;7379.409647228316;7930.762077137695;7960.960195198987;8277.793055173428;11458.94270528642;12071.336076515876;12508;13127.671808204872;13550.728985420434;11358.772824543297;11519;11562.421878905852;11612.419379030845;11764.470598822902;9099.317551183556;11336.659900203007;11339.149563782585;11422.200445968703;11431.942840285745;11409.657710268373;11512.424378781196;11515.539378425105;11777.234479758788;11917.880821192024;7287.890681639886;7442.367398770949;7498.850022999619;7556.433267505069;7657.770266892002;10819.459027048773;11379.032782213273;11494.94252528732;11549.42252887369;11576.768464630828 00:18:47 INFO - PROCESS | 1660 | |3;mootools.html;1262.7032647327876;1504.3681653705444;1652.1161178769362;1766.5936834528065;1842.6683197024531;7983.640736166872;8509.829803403929;8606.311495080394;9007.819843603133;9042.773930651729;8596.95701521493;8776.385653004292;8780.648774049047;8787.384883058188;8816.735497935073;2362.2913126062153;2407.5064611754638;2409.795167410772;2412.07135252928;2418.6492958521003;2041.2345370486069;2361.4686695493547;2398.9280321590295;2417.685700858886;2420.7095148582193;1986.04669758516;2398.8680622565753;2399.460121472672;2411.4574220800355;2414.5653782319177;1992.14337834731;2318.7681231876845;2380.73811880693;2391.1152873436768;2393.270052633937;2381.6546600742968;2382.988085059581;2384.8926798293946;2385.1294277588554;2390.032037025008;8786.121387861227;8913.643454261823;8940.195382415613;8956.8656470153;8963.641454341818;5221.034108689904;5245.79016839326;5297.867553311159;5360.9463905360835;5382.407935127172;3533.6819686228364;3659.1766852457986;3687.078230442389;3741.56971948224;3743.7192210584317;878.96044677989;1078.6224821312555;1079.8272276435812;1117.899389054981;1135.9545618175264;9735.17251033663;10296.382217067;11359.261647992855;11482.311061336262;11595.884041159565;4014.237294913957;4192.972721683146;4546.977265113654;4638.141943740419;4825.95174048262;9476.384035037701;9893.307468477282;9897.950510247403;9903.504824758733;9921.156701680293;5299.973500132474;5330.786768529216;5381.730913454312;5381.946180538144;5395.3525576931015;4095.774732389747;4413.293872980308;4760.904781904412;4849.709017458964;4926;3882.2235552889533;3907.081835768592;3978.6618137458063;4008.8396464141106;4045.696572756996;1294.6116165150418;1302.2056545507232;1303.2636560343376;1313.875181857722;1322.927239001864;734.8273155808381;858.6222062292571;900.8333458310234;910.5993362920294;944.6693657220055;5343.7862485500145;5681.176229446707;5792.710364481759;5916.7337469813965;6122.816315510542;957.2007373842919;957.5786653872414;962.379265373844;975.9609615615437;1011.7116621762763;3501.844391350848;3592.586852511942;3913;3983.3029219886403;4080.8571699990357;10488.160947124212;10790.73023174427;10814.567417303377;10821.026107650348;10836.295640783324;10670.51982660782;10709.303895246783;10754.677359679223;10805.243632945618;10806.891931080745;7145.17830449505;7803.453758236869;8100.83798324042;8164.101948785629;8310.335173186131;5331.960267747752;5999.790007349722;6026.216591843032;6135;6189.071639254148;8439.324854011787;9629.277804164856;9995.450250236016;10003.649872254728;10101.69694909154;10100.242481813746;10123.088921997056;10198.490075496047;10253.12848407864;10352.171826254033;6487.3836985486305;7505.512141710771;7512.849743005218;7573.697052117854;7629.809254768453;8169.428140030141;8238;8297.917020829957;8310.501369917823;8339.583020849055;7323.157836848831;7434.405247580073;7442.665080071518;7502.774916752506;7563.054618172729;6163.322034576104;7252.601106939169;7269.0186824777975;7406.333429991327;7471.850562988601;6910.378065974087;6936.306369363023;6969;7045.577265364094;7052.471064670068;5007.073691367109;5076;5076.949230507796;5088.821891233789;5148.794048238028;4960.652754307308;5008.098542262428;5129.333186685707;5196;5245.554127899019 00:18:47 INFO - PROCESS | 1660 | |4;prototype.html;297.3502896171866;304.3168087643241;310.56675937067786;315.8941754512238;317.1025996430103;4994.726344782084;5460.6177567570185;5628.746706398211;5729.770809167633;5765.423457654228;4413.47038355397;5011.799528018884;5527.668339899599;5641.830745077655;5645.633033852797;3630.8910732677928;3728.701703863698;4170.103427763031;4271.850485233017;4295.735241216682;3900.5514365847866;4177.540470548237;4183.142455796555;4314.525402205755;4316.7194132381455;3951.8221680024326;4151.522574903895;4153.252414565377;4158.482154013778;4193;4171.70798044134;4177.0392809653795;4184.4351012613215;4213.30480470722;4217.472815898012;3177.5551422800827;3604.3692353838237;3613.49411082446;3628.6008539060945;3629.65518275766;5061.962822306624;5137.33214682089;5304.20436934459;5428.267183930158;5456.753231827057;4776.880577985544;4784.784684689197;4800.207965685658;4801.399825021872;4808.663393562452;5366.624336296461;5568.470995255444;5875.148103524965;5890.764369425261;5934.406559344075;3257.690519400653;3941.724079314449;3947.4276229946786;3964.453862993435;3972.7020473464318;5971.373005834368;6173.382661733835;6206.193194884681;6257.78097766576;6288.213973253343;3361.596608407007;3506.8947931562093;3988.920221595552;3989.3218152913496;4004.599540045972;3415.0608582639475;3913.3151698452657;3927.1752931884052;3989.1822176453757;4034.5561988181275;4711.199096153662;4721.276733992068;4748.525147485224;4757.167495688241;4767.284907263938;5893.204417403681;5980.910286345708;5998.700064996733;6002.759889604455;6007.309159446632;1236.6599185224136;1370.184740079651;1374.4639590559689;1382.9861701382858;1393.780442113151;783.8164371798512;783.824263604593;784.811645205158;826.557791581501;855.8416692911707;3877.86427475037;3914.706397020235;4221.282381995068;4358.19882410488;4667.066586682677;4187.759548641462;4274.487061552633;5014.34813474246;5112.642115051911;5143.922841157386;4211.168141858301;4535.478419981679;4620;4667.109820522694;4682.986315884203;2138.6471232246818;2259.9322020339414;2611.908583199579;2631.973680263211;2717.728227177306;5247.871707582812;5278.7594915194895;5285.868392664567;5312;5431.321084864392;4433.4236549249035;4514.074614703978;5264.341957255343;5282.415275417359;5309.681419114865;4948.529889660476;5208.296879921238;5231.712255825966;5301.363836339664;5340.305760251143;4671.252599584051;4902.039184326298;5145.8936328689515;5162.303089082926;5201.7399130044105;4361.890952726208;4811.566958973709;5047.848564543069;5058.317127187783;5117.5905927526455;4457.621102206349;5094.770735316845;5160.071187186342;5229.966619360837;5303.867403314948;3011.65365982906;3085.2595377109783;3158.652548219648;3166.7624928130945;3577.2130131371055;4389.758563279051;4647.395838540968;4927.802887884444;5019.548240658358;5165.147750621256;5710.2862142232225;5727.341355744143;5894.174815525744;5947.553933454921;6008.969955150196;5323.494268044529;5976.970115149396;5984.281886173687;5990.143055652761;6001.819945401645;4923.532264434873;5118.3090282811345;5486.643368181056;5737.397573254748;5805.477507024388;5870.178175055581;5918.911216331672;5919.372172652383;5963.880722385441;6010.609310394811;5442.319710036246;5636.577256705681;5797.76808927655;5807.245058142584;5812.273465816773 00:18:47 INFO - PROCESS | 1660 | |5;yui.html;635.5467893929432;675.5946432140719;724.9234886194001;729.7482368582839;740.4039748002856;15160.166190859512;15790;15945.84054159458;16083.919580402082;16219;14699.265036748173;14837.406503739838;14930.776038359407;14990.925045374757;15009.474668386609;690.3924546399162;866.2030931542964;888.1473785166248;888.9466632002101;892.3887137310962;860.9612567434449;877.9868301975475;877.9956100219522;878.4948654523674;881.2245224202693;889.7241855024963;927.1331305229598;929.2333824594702;929.809389075238;930.5672862119113;910.804177101923;919.889613246408;922.1976880114277;923.3767207135157;923.4044041593169;680.4386381235452;684.2336583026992;687.3126873126873;688.3770187979886;688.8071340024801;14999.775003374849;15027.699446011127;15053.849461505355;15091.320890559951;15113.848861511355;11358.091352691765;11834.585789497327;14556.163230963315;14593.27033648324;14597.270136493133;8720.607572659246;9656.71029869105;10210.336328138721;11866.644000679993;11879.168458207929;3778.2065766188857;3783.886483405502;3804.3912973924316;3832.9425058624142;3921;112316.43841780821;127808.36095819554;128352;130845.69154308431;133650;9431.339806213637;11083.038068043166;11383.260088094248;11408.657740267805;11429.371384573928;23189;23208.53582928332;23237;23273.650895236584;23334.766652333597;7290.161631412349;7723.150453450116;7898.815177723387;8083.514989100673;8164.265216130458;3865.9613403866165;4418.955810441855;4644.86065418038;4668.5798278155125;4792.0895029944195;3507.15828201235;3562.251927095287;3613.674769270778;3618.782873027627;3624.5288112545727;515.4536191636877;568.7668056097064;625.7090452939404;642.6401215319436;675.8918573028391;516.5506009771523;611.6635152192474;629.6820105846518;663.1875951958812;683.8837397642412;9346.439213647203;9447.244220462484;9492.193163581034;9581.856272155923;9633.951830240803;407.97348172369294;524.779592571121;555.7977332170877;613.6686189457643;639.4326777986388;3971.3645816669195;4458.576435238647;4678.087772884323;4686;4773.928391074067;143082;145462;147247.2637636805;148689;148772;87667.56166219128;130524;131667.34166329107;133580;137809;107292.46353768182;107924;110661;111692.88307116987;112707.43646281716;8180.918190818015;8980.236679882022;9037.909620903707;9060.320475964461;9122.452652840851;47041;59794;60291.49271268078;60351.39648603458;61484.385156147866;74804;84083;87339;92716;98483.50758246164;11380.260283081574;11453.713657158638;11734.295942243492;11957;12153.210041347284;12123.878761212274;12381.071419643382;12455.688607784878;12555.43500542496;12945.223286602833;11606.30362178272;12029.45867435985;12177.02583793312;12291.754164916472;12406.255624662188;11473.426328683365;11539.307641541534;11542.538298468304;11586.88413115858;11623.709407264549;10552.944705529386;12023.45894434735;12085.697857553632;12234.694132646755;12345.506179752709;9772.902270977484;10142.442165680959;10161.847572286273;10186.643467478603;10677.626283080055;10115.79768404613;10352.741181470523;10470;10538.262321637412;10619.415932123498 00:18:47 INFO - PROCESS | 1660 | __end_tp_report 00:18:47 INFO - PROCESS | 1660 | __start_cc_report 00:18:47 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,366 00:18:47 INFO - PROCESS | 1660 | __end_cc_report 00:18:47 INFO - PROCESS | 1660 | __startTimestamp1472973527801__endTimestamp 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | ------- Summary: start ------- 00:18:47 INFO - PROCESS | 1660 | Number of tests: 6 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5715.23 Median:5949.73 stddev:662.95 (11.1%) stddev-sans-first:637.57 00:18:47 INFO - PROCESS | 1660 | Values: 3309.7 3337.7 3372.5 3376.3 3393.2 5649.1 5669.2 5718.3 5808.7 5822.8 5058.3 5702.8 5755.2 5811.4 5815.2 5163.9 5213.6 5257.7 5316.7 5322.9 5224.4 5388.7 5407.4 5412.6 5418.0 5149.9 5205.4 5220.0 5263.4 5274.9 5242.8 5263.6 5267.4 5292.3 5305.5 5188.5 5195.6 5200.3 5234.6 5303.3 4003.1 4717.7 5626.5 5691.7 5774.5 6203.3 6230.8 6259.0 6260.2 6289.5 6221.5 6231.0 6255.3 6265.1 6282.9 5778.7 5807.1 5863.5 5929.6 5949.7 6287.7 6296.1 6302.5 6308.6 6319.0 6073.5 6122.3 6128.4 6134.3 6137.8 5814.6 6112.5 6129.6 6189.7 6202.5 6100.2 6117.5 6158.2 6176.5 6187.0 5211.5 5677.3 6248.7 6259.4 6261.9 4864.1 4908.2 4967.5 4986.0 4999.1 3781.5 4402.7 4441.2 4446.6 4544.0 5625.0 5643.5 5772.3 5784.7 5797.1 6113.4 6237.4 6244.4 6261.2 6288.0 5701.3 5749.1 5750.9 5782.2 5875.0 5428.5 5432.2 5432.8 5448.6 5450.4 6287.9 6307.8 6310.9 6315.5 6325.8 6104.1 6161.4 6199.7 6200.7 6216.6 6302.9 6308.6 6321.2 6348.6 6376.5 5456.4 5987.9 6154.4 6227.7 6239.1 6128.0 6158.8 6163.8 6205.7 6261.7 6102.8 6160.1 6174.0 6174.5 6181.5 6043.5 6098.9 6151.4 6161.4 6166.4 4709.7 5110.3 5812.7 6031.8 6058.6 6136.4 6143.2 6147.2 6169.1 6171.3 5890.8 6003.7 6009.6 6013.8 6028.7 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:71451.03 Median:40343.99 stddev:159512.07 (395.4%) stddev-sans-first:159870.55 00:18:47 INFO - PROCESS | 1660 | Values: 483.5 537.6 537.7 543.1 544.5 28123.9 40868.6 40919.4 40966.2 40989.4 41595.6 41661.2 42345.0 42384.4 42427.0 9646.0 9781.3 9813.5 9847.7 9881.4 9640.9 9646.0 9658.5 9659.0 9757.7 9594.5 9756.7 9785.1 9788.8 9820.7 9719.4 9756.2 9771.9 9784.9 9799.7 7791.9 8604.1 9541.1 9779.0 9779.7 31634.0 39994.4 40095.0 40169.4 40210.4 3313.2 3341.5 3346.3 3558.2 3699.2 25239.9 30577.4 30583.5 30808.8 30922.8 3255.6 3286.2 3289.2 3291.0 3344.6 483926.0 484703.0 488463.6 489863.0 490648.0 61461.0 61909.4 61934.1 61974.0 62115.7 34830.7 35286.1 35494.3 36210.5 64664.4 16509.4 16672.7 16678.6 16706.6 16720.4 12706.6 12820.4 12836.9 12852.5 12874.5 19329.5 19397.1 19635.0 19671.4 19722.2 7069.0 7616.5 7943.8 7964.4 8016.3 839.9 927.9 981.5 995.4 1028.1 29957.4 30943.4 31018.2 31024.1 31577.0 945.5 994.3 1071.6 1072.7 1080.7 15210.8 15838.2 15847.9 15918.4 16003.0 684852.0 685033.0 690940.0 693859.0 700602.0 57608.7 59159.4 59326.0 59372.8 59456.0 1371.6 1392.1 1396.2 1408.5 1409.1 29184.4 30238.2 30316.8 30633.7 30687.5 52257.7 52476.7 52726.9 52748.0 52770.9 581313.0 591880.0 592209.0 593713.0 595581.0 26813.0 26912.7 27207.2 27320.6 27321.2 47020.0 47074.5 48053.0 48134.0 48362.5 32592.0 39183.0 39376.0 40439.2 40991.4 38934.0 39123.8 39339.0 40248.8 40447.0 32816.9 37728.4 38059.0 38468.8 38516.6 28768.0 32474.0 33060.0 33852.2 34905.7 20085.7 23832.6 25779.1 25889.0 26335.9 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34361.52 Median:13673.77 stddev:96412.90 (705.1%) stddev-sans-first:96649.59 00:18:47 INFO - PROCESS | 1660 | Values: 371.1 374.5 380.6 385.4 399.9 16119.7 19141.6 20146.9 21791.2 22036.8 20391.9 20514.8 21005.6 21073.0 21299.4 5890.5 6181.9 6222.2 6223.2 6241.8 5917.6 6082.0 6082.3 6157.3 6182.3 6003.8 6085.7 6097.1 6106.5 6118.4 5933.2 6072.9 6095.9 6118.4 6128.3 4363.3 5192.7 5298.7 5302.4 5308.8 25033.0 25291.9 25363.2 26408.1 26560.3 2197.2 2217.2 2234.1 2314.5 3061.1 12960.6 15310.8 15855.2 16031.0 16443.8 1836.8 1885.3 1896.3 1960.1 1986.3 117976.0 122177.0 122579.0 123462.0 123589.0 10718.0 10777.0 11198.2 11235.3 11385.4 12056.2 12798.7 13418.5 13664.6 13704.5 8085.7 8139.1 8297.6 8551.3 8747.9 7163.2 8610.0 8631.2 8693.9 8832.4 5752.6 6440.0 6600.6 6695.4 6752.5 1758.9 1845.6 1877.6 1893.3 1916.3 691.0 793.3 898.5 920.7 927.5 18161.9 21890.0 22290.1 22401.3 22523.5 915.5 968.3 974.3 976.3 1013.2 11517.4 11794.7 11904.4 11929.6 12041.9 524884.0 579452.0 582900.0 599657.0 599992.0 116613.3 117118.0 117974.4 119173.2 121332.0 975.1 990.0 999.0 1002.9 1003.3 18980.5 19686.0 19884.8 19893.5 19904.3 50021.7 51287.0 51620.7 51773.0 52604.0 72242.0 72959.2 73538.9 74429.0 74837.6 6991.9 7379.4 7930.8 7961.0 8277.8 11458.9 12071.3 12508.0 13127.7 13550.7 11358.8 11519.0 11562.4 11612.4 11764.5 9099.3 11336.7 11339.1 11422.2 11431.9 11409.7 11512.4 11515.5 11777.2 11917.9 7287.9 7442.4 7498.9 7556.4 7657.8 10819.5 11379.0 11494.9 11549.4 11576.8 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5667.14 Median:5082.89 stddev:3153.95 (62.1%) stddev-sans-first:3145.42 00:18:47 INFO - PROCESS | 1660 | Values: 1262.7 1504.4 1652.1 1766.6 1842.7 7983.6 8509.8 8606.3 9007.8 9042.8 8597.0 8776.4 8780.6 8787.4 8816.7 2362.3 2407.5 2409.8 2412.1 2418.6 2041.2 2361.5 2398.9 2417.7 2420.7 1986.0 2398.9 2399.5 2411.5 2414.6 1992.1 2318.8 2380.7 2391.1 2393.3 2381.7 2383.0 2384.9 2385.1 2390.0 8786.1 8913.6 8940.2 8956.9 8963.6 5221.0 5245.8 5297.9 5360.9 5382.4 3533.7 3659.2 3687.1 3741.6 3743.7 879.0 1078.6 1079.8 1117.9 1136.0 9735.2 10296.4 11359.3 11482.3 11595.9 4014.2 4193.0 4547.0 4638.1 4826.0 9476.4 9893.3 9898.0 9903.5 9921.2 5300.0 5330.8 5381.7 5381.9 5395.4 4095.8 4413.3 4760.9 4849.7 4926.0 3882.2 3907.1 3978.7 4008.8 4045.7 1294.6 1302.2 1303.3 1313.9 1322.9 734.8 858.6 900.8 910.6 944.7 5343.8 5681.2 5792.7 5916.7 6122.8 957.2 957.6 962.4 976.0 1011.7 3501.8 3592.6 3913.0 3983.3 4080.9 10488.2 10790.7 10814.6 10821.0 10836.3 10670.5 10709.3 10754.7 10805.2 10806.9 7145.2 7803.5 8100.8 8164.1 8310.3 5332.0 5999.8 6026.2 6135.0 6189.1 8439.3 9629.3 9995.5 10003.6 10101.7 10100.2 10123.1 10198.5 10253.1 10352.2 6487.4 7505.5 7512.8 7573.7 7629.8 8169.4 8238.0 8297.9 8310.5 8339.6 7323.2 7434.4 7442.7 7502.8 7563.1 6163.3 7252.6 7269.0 7406.3 7471.9 6910.4 6936.3 6969.0 7045.6 7052.5 5007.1 5076.0 5076.9 5088.8 5148.8 4960.7 5008.1 5129.3 5196.0 5245.6 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4461.11 Median:4856.80 stddev:1406.53 (29.0%) stddev-sans-first:1375.32 00:18:47 INFO - PROCESS | 1660 | Values: 297.4 304.3 310.6 315.9 317.1 4994.7 5460.6 5628.7 5729.8 5765.4 4413.5 5011.8 5527.7 5641.8 5645.6 3630.9 3728.7 4170.1 4271.9 4295.7 3900.6 4177.5 4183.1 4314.5 4316.7 3951.8 4151.5 4153.3 4158.5 4193.0 4171.7 4177.0 4184.4 4213.3 4217.5 3177.6 3604.4 3613.5 3628.6 3629.7 5062.0 5137.3 5304.2 5428.3 5456.8 4776.9 4784.8 4800.2 4801.4 4808.7 5366.6 5568.5 5875.1 5890.8 5934.4 3257.7 3941.7 3947.4 3964.5 3972.7 5971.4 6173.4 6206.2 6257.8 6288.2 3361.6 3506.9 3988.9 3989.3 4004.6 3415.1 3913.3 3927.2 3989.2 4034.6 4711.2 4721.3 4748.5 4757.2 4767.3 5893.2 5980.9 5998.7 6002.8 6007.3 1236.7 1370.2 1374.5 1383.0 1393.8 783.8 783.8 784.8 826.6 855.8 3877.9 3914.7 4221.3 4358.2 4667.1 4187.8 4274.5 5014.3 5112.6 5143.9 4211.2 4535.5 4620.0 4667.1 4683.0 2138.6 2259.9 2611.9 2632.0 2717.7 5247.9 5278.8 5285.9 5312.0 5431.3 4433.4 4514.1 5264.3 5282.4 5309.7 4948.5 5208.3 5231.7 5301.4 5340.3 4671.3 4902.0 5145.9 5162.3 5201.7 4361.9 4811.6 5047.8 5058.3 5117.6 4457.6 5094.8 5160.1 5230.0 5303.9 3011.7 3085.3 3158.7 3166.8 3577.2 4389.8 4647.4 4927.8 5019.5 5165.1 5710.3 5727.3 5894.2 5947.6 6009.0 5323.5 5977.0 5984.3 5990.1 6001.8 4923.5 5118.3 5486.6 5737.4 5805.5 5870.2 5918.9 5919.4 5963.9 6010.6 5442.3 5636.6 5797.8 5807.2 5812.3 00:18:47 INFO - PROCESS | 1660 | 00:18:47 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:24620.60 Median:3781.05 stddev:40124.34 (1061.2%) stddev-sans-first:40196.48 00:18:47 INFO - PROCESS | 1660 | Values: 635.5 675.6 724.9 729.7 740.4 15160.2 15790.0 15945.8 16083.9 16219.0 14699.3 14837.4 14930.8 14990.9 15009.5 690.4 866.2 888.1 888.9 892.4 861.0 878.0 878.0 878.5 881.2 889.7 927.1 929.2 929.8 930.6 910.8 919.9 922.2 923.4 923.4 680.4 684.2 687.3 688.4 688.8 14999.8 15027.7 15053.8 15091.3 15113.8 11358.1 11834.6 14556.2 14593.3 14597.3 8720.6 9656.7 10210.3 11866.6 11879.2 3778.2 3783.9 3804.4 3832.9 3921.0 112316.4 127808.4 128352.0 130845.7 133650.0 9431.3 11083.0 11383.3 11408.7 11429.4 23189.0 23208.5 23237.0 23273.7 23334.8 7290.2 7723.2 7898.8 8083.5 8164.3 3866.0 4419.0 4644.9 4668.6 4792.1 3507.2 3562.3 3613.7 3618.8 3624.5 515.5 568.8 625.7 642.6 675.9 516.6 611.7 629.7 663.2 683.9 9346.4 9447.2 9492.2 9581.9 9634.0 408.0 524.8 555.8 613.7 639.4 3971.4 4458.6 4678.1 4686.0 4773.9 143082.0 145462.0 147247.3 148689.0 148772.0 87667.6 130524.0 131667.3 133580.0 137809.0 107292.5 107924.0 110661.0 111692.9 112707.4 8180.9 8980.2 9037.9 9060.3 9122.5 47041.0 59794.0 60291.5 60351.4 61484.4 74804.0 84083.0 87339.0 92716.0 98483.5 11380.3 11453.7 11734.3 11957.0 12153.2 12123.9 12381.1 12455.7 12555.4 12945.2 11606.3 12029.5 12177.0 12291.8 12406.3 11473.4 11539.3 11542.5 11586.9 11623.7 10552.9 12023.5 12085.7 12234.7 12345.5 9772.9 10142.4 10161.8 10186.6 10677.6 10115.8 10352.7 10470.0 10538.3 10619.4 00:18:47 INFO - PROCESS | 1660 | -------- Summary: end -------- 00:18:47 INFO - PROCESS | 1660 | 00:18:48 INFO - TEST-INFO | 1660: exit 0 00:18:48 INFO - TEST-OK | dromaeo_css | took 1113758ms 00:18:48 INFO - TEST-START | kraken 00:18:48 INFO - operating with platform_type : mac_ 00:18:48 INFO - Initialising browser for kraken test... 00:18:48 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpcxmpcV/profile http://localhost:49276/getInfo.html) 00:18:51 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 00:18:51 INFO - PROCESS | 1667 | colorDepth:24 00:18:51 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 00:18:51 INFO - PROCESS | 1667 | __metrics 00:18:51 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 00:18:51 INFO - TEST-INFO | 1667: exit 0 00:18:51 INFO - Browser initialized. 00:18:51 INFO - Running cycle 1/1 for kraken test... 00:18:51 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpcxmpcV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:19:03 INFO - PROCESS | 1669 | RSS: Main: 245374976 00:19:03 INFO - PROCESS | 1669 | 00:19:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 00:19:08 INFO - PROCESS | 1669 | RSS: Main: 258043904 00:19:08 INFO - PROCESS | 1669 | 00:19:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 00:19:11 INFO - PROCESS | 1669 | RSS: Main: 266694656 00:19:11 INFO - PROCESS | 1669 | 00:19:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 00:19:15 INFO - PROCESS | 1669 | RSS: Main: 264757248 00:19:15 INFO - PROCESS | 1669 | 00:19:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 00:19:18 INFO - PROCESS | 1669 | RSS: Main: 266833920 00:19:18 INFO - PROCESS | 1669 | 00:19:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:19:20 INFO - PROCESS | 1669 | RSS: Main: 267440128 00:19:20 INFO - PROCESS | 1669 | 00:19:23 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 00:19:24 INFO - PROCESS | 1669 | RSS: Main: 267403264 00:19:24 INFO - PROCESS | 1669 | 00:19:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 00:19:28 INFO - PROCESS | 1669 | RSS: Main: 267780096 00:19:28 INFO - PROCESS | 1669 | 00:19:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 00:19:32 INFO - PROCESS | 1669 | RSS: Main: 268013568 00:19:32 INFO - PROCESS | 1669 | 00:19:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:19:34 INFO - PROCESS | 1669 | RSS: Main: 267517952 00:19:34 INFO - PROCESS | 1669 | 00:19:36 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:19:36 INFO - PROCESS | 1669 | RSS: Main: 267661312 00:19:36 INFO - PROCESS | 1669 | 00:19:38 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:19:39 INFO - PROCESS | 1669 | RSS: Main: 267993088 00:19:39 INFO - PROCESS | 1669 | 00:19:42 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:19:43 INFO - PROCESS | 1669 | RSS: Main: 268095488 00:19:43 INFO - PROCESS | 1669 | 00:19:46 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:19:47 INFO - PROCESS | 1669 | RSS: Main: 268111872 00:19:47 INFO - PROCESS | 1669 | 00:19:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 00:19:49 INFO - PROCESS | 1669 | RSS: Main: 271089664 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | __start_tp_report 00:19:49 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 00:19:49 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 00:19:49 INFO - PROCESS | 1669 | |i|pagename|runs| 00:19:49 INFO - PROCESS | 1669 | |0;ai-astar;110;145;107;95;159;155;121;107;154;193 00:19:49 INFO - PROCESS | 1669 | |1;audio-beat-detection;152;114;127;200;126;156;118;141;197;141 00:19:49 INFO - PROCESS | 1669 | |2;audio-dft;248;259;244;270;196;197;194;197;194;198 00:19:49 INFO - PROCESS | 1669 | |3;audio-fft;69;83;77;153;87;84;80;61;146;86 00:19:49 INFO - PROCESS | 1669 | |4;audio-oscillator;69;74;120;71;69;92;111;69;74;112 00:19:49 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;91;90;91;84;91;88;93;90;91;90 00:19:49 INFO - PROCESS | 1669 | |6;imaging-darkroom;137;99;96;97;96;126;109;151;114;143 00:19:49 INFO - PROCESS | 1669 | |7;imaging-desaturate;113;136;148;94;93;90;92;152;154;98 00:19:49 INFO - PROCESS | 1669 | |8;json-parse-financial;55;58;58;58;58;56;57;57;57;57 00:19:49 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;66;69;70;75;78;63;95;103;104;102 00:19:49 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;90;85;88;89;90;87;86;93;93;88 00:19:49 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;143;142;157;141;161;162;153;143;189;142 00:19:49 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;222;232;213;175;247;185;161;175;176;143 00:19:49 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;68;67;68;70;68;67;71;69;73;92 00:19:49 INFO - PROCESS | 1669 | __end_tp_report 00:19:49 INFO - PROCESS | 1669 | __start_cc_report 00:19:49 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,957 00:19:49 INFO - PROCESS | 1669 | __end_cc_report 00:19:49 INFO - PROCESS | 1669 | __startTimestamp1472973589107__endTimestamp 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | ------- Summary: start ------- 00:19:49 INFO - PROCESS | 1669 | Number of tests: 14 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:134.60 Median:154.50 stddev:31.25 (20.2%) stddev-sans-first:31.85 00:19:49 INFO - PROCESS | 1669 | Values: 110.0 145.0 107.0 95.0 159.0 155.0 121.0 107.0 154.0 193.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:146.50 stddev:30.29 (20.7%) stddev-sans-first:32.08 00:19:49 INFO - PROCESS | 1669 | Values: 152.0 114.0 127.0 200.0 126.0 156.0 118.0 141.0 197.0 141.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:219.70 Median:221.00 stddev:31.36 (14.2%) stddev-sans-first:31.54 00:19:49 INFO - PROCESS | 1669 | Values: 248.0 259.0 244.0 270.0 196.0 197.0 194.0 197.0 194.0 198.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:92.60 Median:81.50 stddev:31.09 (38.1%) stddev-sans-first:31.78 00:19:49 INFO - PROCESS | 1669 | Values: 69.0 83.0 77.0 153.0 87.0 84.0 80.0 61.0 146.0 86.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:70.00 stddev:20.74 (29.6%) stddev-sans-first:21.06 00:19:49 INFO - PROCESS | 1669 | Values: 69.0 74.0 120.0 71.0 69.0 92.0 111.0 69.0 74.0 112.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:91.00 stddev:2.42 (2.7%) stddev-sans-first:2.54 00:19:49 INFO - PROCESS | 1669 | Values: 91.0 90.0 91.0 84.0 91.0 88.0 93.0 90.0 91.0 90.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:116.80 Median:123.50 stddev:21.06 (17.1%) stddev-sans-first:21.03 00:19:49 INFO - PROCESS | 1669 | Values: 137.0 99.0 96.0 97.0 96.0 126.0 109.0 151.0 114.0 143.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:117.00 Median:91.00 stddev:27.39 (30.1%) stddev-sans-first:29.01 00:19:49 INFO - PROCESS | 1669 | Values: 113.0 136.0 148.0 94.0 93.0 90.0 92.0 152.0 154.0 98.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:0.99 (1.7%) stddev-sans-first:0.71 00:19:49 INFO - PROCESS | 1669 | Values: 55.0 58.0 58.0 58.0 58.0 56.0 57.0 57.0 57.0 57.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:82.50 Median:69.50 stddev:16.62 (23.9%) stddev-sans-first:16.52 00:19:49 INFO - PROCESS | 1669 | Values: 66.0 69.0 70.0 75.0 78.0 63.0 95.0 103.0 104.0 102.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:89.50 stddev:2.69 (3.0%) stddev-sans-first:2.82 00:19:49 INFO - PROCESS | 1669 | Values: 90.0 85.0 88.0 89.0 90.0 87.0 86.0 93.0 93.0 88.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:155.00 stddev:15.06 (9.7%) stddev-sans-first:15.51 00:19:49 INFO - PROCESS | 1669 | Values: 143.0 142.0 157.0 141.0 161.0 162.0 153.0 143.0 189.0 142.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.90 Median:199.00 stddev:33.67 (16.9%) stddev-sans-first:34.03 00:19:49 INFO - PROCESS | 1669 | Values: 222.0 232.0 213.0 175.0 247.0 185.0 161.0 175.0 176.0 143.0 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:69.50 stddev:7.51 (10.8%) stddev-sans-first:7.87 00:19:49 INFO - PROCESS | 1669 | Values: 68.0 67.0 68.0 70.0 68.0 67.0 71.0 69.0 73.0 92.0 00:19:49 INFO - PROCESS | 1669 | -------- Summary: end -------- 00:19:49 INFO - PROCESS | 1669 | 00:19:49 INFO - TEST-INFO | 1669: exit 0 00:19:49 INFO - TEST-OK | kraken | took 61530ms 00:19:49 INFO - SUITE-END | took 1175s 00:19:50 INFO - Completed test suite (00:19:35) 00:19:50 INFO - javascript benchmark 00:19:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7310.64759479838, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3309.735221182304, 3337.6662333766617, 3372.4772660237654, 3376.3416133853907, 3393.1856354474935, 5649.124385720214, 5669.177969194464, 5718.342390625075, 5808.680522571268, 5822.82531524055, 5058.291839142523, 5702.77188912444, 5755.223044888939, 5811.4479124483105, 5815.156802263669, 5163.870903227421, 5213.556847667934, 5257.658252213613, 5316.680999140064, 5322.946770532303, 5224.425313215562, 5388.703621300827, 5407.432219616942, 5412.621116521845, 5417.972910135444, 5149.871253218662, 5205.375354957429, 5219.973900130494, 5263.421023687391, 5274.868128296785, 5242.79028838846, 5263.552598029172, 5267.3679158500845, 5292.338457692788, 5305.549028332596, 5188.45521220274, 5195.610329225286, 5200.323957885488, 5234.555062819665, 5303.310569625963, 4003.0592810689463, 4717.693349932278, 5626.465485778845, 5691.743871525791, 5774.451427114417, 6203.255609326865, 6230.7819226326865, 6258.968705156491, 6260.15487909131, 6289.496840252815, 6221.471174950135, 6231.034189700604, 6255.280642726099, 6265.0915617235705, 6282.87434251317, 5778.653280803207, 5807.128930660435, 5863.472287494061, 5929.584929054968, 5949.732262048218, 6287.654179020198, 6296.118543403927, 6302.4958003359625, 6308.589941653778, 6319.0, 6073.544484163705, 6122.326544080148, 6128.3565225651555, 6134.294556126013, 6137.754489820447, 5814.622049566765, 6112.473085128069, 6129.632222066691, 6189.721462534107, 6202.534809889276, 6100.217864923808, 6117.4620395853735, 6158.230221222348, 6176.536759743038, 6187.041008643668, 5211.478852114758, 5677.318721753416, 6248.718807653667, 6259.374062593704, 6261.8747625047245, 4864.075825593126, 4908.190148625508, 4967.453580106186, 4986.0, 4999.075171093359, 3781.4516895050065, 4402.691811573223, 4441.244988351988, 4446.644268458515, 4543.977280113579, 5624.971875140598, 5643.463870932255, 5772.307323121168, 5784.71076446176, 5797.130430435468, 6113.38866113394, 6237.376262373817, 6244.37556244381, 6261.154744109578, 6288.0, 5701.258836351248, 5749.108888122348, 5750.884982300414, 5782.248307720053, 5875.030619947661, 5428.45715428454, 5432.239486471897, 5432.809851655252, 5448.618596698193, 5450.427705090988, 6287.937120628918, 6307.842303942346, 6310.873782524415, 6315.494760419156, 6325.7785977491485, 6104.125697713769, 6161.353057928853, 6199.721012554356, 6200.658963756856, 6216.564840461305, 6302.936970630235, 6308.621482711052, 6321.209848768904, 6348.587341822767, 6376.521760868046, 5456.42707515721, 5987.850303742442, 6154.415330543591, 6227.657478838707, 6239.064140378798, 6128.044828652092, 6158.753649853956, 6163.7842675506145, 6205.689715514297, 6261.718222680081, 6102.816915492543, 6160.075988601746, 6173.98129308659, 6174.506039516918, 6181.53638477125, 6043.456088952015, 6098.908516372347, 6151.446369826737, 6161.383861613803, 6166.352532983972, 4709.740964246862, 5110.3356563646485, 5812.651240925558, 6031.75872965094, 6058.57589968698, 6136.355682653436, 6143.1706719592285, 6147.231596050494, 6169.136320914986, 6171.321154672893, 5890.764369425175, 6003.729832157476, 6009.609375390761, 6013.849653758691, 6028.668423236764], "value": 5673.725216506197, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.45852645037553, 537.569944044764, 537.6773935638616, 543.1228565866128, 544.4854612391292, 28123.859380703092, 40868.59131408685, 40919.38620920681, 40966.18067638645, 40989.3851592226, 41595.58404415955, 41661.166776664446, 42345.0, 42384.36423453651, 42427.0, 9646.035396460367, 9781.315307928448, 9813.460259685715, 9847.704568862946, 9881.407115573053, 9640.855387169164, 9646.035396460333, 9658.517074146299, 9659.0, 9757.658481953133, 9594.520273986273, 9756.658516951908, 9785.07041831025, 9788.755281117958, 9820.705378838646, 9719.416834989923, 9756.170725488306, 9771.853422198672, 9784.902150978542, 9799.706008819747, 7791.948087008238, 8604.096569860138, 9541.141297283279, 9779.022097790164, 9779.657711980117, 31634.0, 39994.400083998764, 40095.0, 40169.39745903814, 40210.39684404736, 3313.188268874117, 3341.465365541502, 3346.330733853215, 3558.19940513385, 3699.1676872703697, 25239.873800631063, 30577.388452231055, 30583.541246881316, 30808.845955770303, 30922.84538577315, 3255.609326880766, 3286.1620286826706, 3289.227031647561, 3290.9835450822834, 3344.5819272590925, 483926.0, 484703.0, 488463.55768221285, 489863.0, 490648.0, 61461.0, 61909.38090619126, 61934.0709889352, 61974.0, 62115.689421552604, 34830.65169348274, 35286.117847053516, 35494.29011419809, 36210.456843147374, 64664.353356466774, 16509.422170223985, 16672.74990875138, 16678.58303542397, 16706.582335441468, 16720.41478548245, 12706.555270565486, 12820.358982051046, 12836.908862746584, 12852.48590056406, 12874.485020599259, 19329.53245156866, 19397.094467144605, 19635.0, 19671.409857704293, 19722.211111555378, 7069.0, 7616.543007419573, 7943.83650627097, 7964.442489025712, 8016.278534931767, 839.9286060684788, 927.9257659387233, 981.4542096198516, 995.4077323992213, 1028.126092821096, 29957.40085198284, 30943.381132377228, 31018.224544386572, 31024.0692779217, 31577.0, 945.5414124149781, 994.3089552760762, 1071.6249312740479, 1072.7264547540328, 1080.7028067281403, 15210.771838422432, 15838.208089595475, 15847.920760396124, 15918.363265469252, 16002.959807612468, 684852.0, 685033.0, 690940.0, 693859.0, 700602.0, 57608.71195644079, 59159.40840591539, 59326.0, 59372.812543748885, 59456.0, 1371.5542448704211, 1392.1090502078678, 1396.211140705498, 1408.457743768677, 1409.0770545292849, 29184.41631167407, 30238.244043899078, 30316.84841575778, 30633.693663063976, 30687.539686904267, 52257.7387113062, 52476.73761631167, 52726.8909243626, 52748.0, 52770.94458110893, 581313.0, 591880.0, 592209.0, 593713.0, 595581.0, 26813.0, 26912.730872691805, 27207.183784486497, 27320.59019114675, 27321.180364589094, 47020.0, 47074.52925470701, 48053.0, 48134.03731925412, 48362.51637483721, 32592.0, 39183.020424489616, 39376.01559961024, 40439.1912161761, 40991.38512922368, 38934.0, 39123.80438097791, 39339.0, 40248.798756006036, 40447.0, 32816.91337842638, 37728.43407348947, 38059.0, 38468.807655961544, 38516.61483385242, 28768.0, 32474.02577922666, 33060.0, 33852.153696157795, 34905.65094349024, 20085.698714519003, 23832.642510362704, 25779.097731579306, 25889.0, 26335.868320658275], "value": 19874.426257243304, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.1111887030562, 374.48882275693677, 380.63078813550857, 385.3583783001303, 399.94000899865034, 16119.677606447893, 19141.617167656637, 20146.89926550369, 21791.237306694267, 22036.779632203674, 20391.898040509815, 20514.794852051476, 21005.579888402222, 21073.0, 21299.36101916941, 5890.499307558863, 6181.90727139091, 6222.191115155046, 6223.190985171921, 6241.812745617639, 5917.615355001911, 6081.969590152065, 6082.300535438437, 6157.261128664567, 6182.258129024523, 6003.759849606033, 6085.726142323584, 6097.085437184435, 6106.450419462248, 6118.378629663142, 5933.228680271559, 6072.878542429149, 6095.939040609582, 6118.449339559461, 6128.295246046717, 4363.323684828857, 5192.688438693691, 5298.708571028592, 5302.443243459458, 5308.840734777963, 25033.0, 25291.873540632365, 25363.239102826945, 26408.075717349802, 26560.335991600172, 2197.2419515267206, 2217.1796435319034, 2234.1398561553756, 2314.5486630107134, 3061.0510741670155, 12960.611181664564, 15310.846891531164, 15855.207239637972, 16030.95798773076, 16443.753343699853, 1836.8438682711985, 1885.279779303749, 1896.3268039845932, 1960.1179469238757, 1986.2650819196845, 117976.0, 122176.99999999911, 122579.0, 123461.9999999991, 123589.0, 10718.0, 10777.0, 11198.216124871344, 11235.325880447199, 11385.37380444067, 12056.156069075256, 12798.68003299925, 13418.530351437652, 13664.590062298146, 13704.52034178799, 8085.716999904975, 8139.145389734111, 8297.626606802709, 8551.273141782895, 8747.868781968276, 7163.212046674862, 8610.0, 8631.223189912938, 8693.91306086931, 8832.425892316978, 5752.626079304831, 6439.967800160969, 6600.636964966973, 6695.393105654608, 6752.493562982796, 1758.9182652668517, 1845.6308738252135, 1877.5775450523797, 1893.2616279650947, 1916.3101283537915, 690.9930900690929, 793.3256731777942, 898.4788822482944, 920.724796157327, 927.536231884058, 18161.90919045423, 21890.014949327, 22290.108395663992, 22401.327960161223, 22523.549529009655, 915.4598786715869, 968.2544440780559, 974.2644303550916, 976.321456587679, 1013.1540163963173, 11517.366544839946, 11794.705132371759, 11904.404779760976, 11929.642110736691, 12041.939790301167, 524884.0, 579452.0, 582900.0, 599657.0, 599992.0, 116613.25080123804, 117118.0, 117974.41012795053, 119173.21240181405, 121332.0, 975.1418751498636, 989.9999999999856, 999.0459111548454, 1002.9398236105711, 1003.3327836988321, 18980.525486862938, 19686.0, 19884.801151988297, 19893.502662433555, 19904.303349382702, 50021.74989125177, 51287.0, 51620.74189629028, 51773.0, 52604.0, 72242.0, 72959.17602059779, 73538.89691654737, 74429.0, 74837.62581187059, 6991.8951215730785, 7379.409647228316, 7930.762077137695, 7960.960195198987, 8277.793055173428, 11458.94270528642, 12071.336076515876, 12508.0, 13127.671808204872, 13550.728985420434, 11358.772824543297, 11519.0, 11562.421878905852, 11612.419379030845, 11764.470598822902, 9099.317551183556, 11336.659900203007, 11339.149563782585, 11422.200445968703, 11431.942840285745, 11409.657710268373, 11512.424378781196, 11515.539378425105, 11777.234479758788, 11917.880821192024, 7287.890681639886, 7442.367398770949, 7498.850022999619, 7556.433267505069, 7657.770266892002, 10819.459027048773, 11379.032782213273, 11494.94252528732, 11549.42252887369, 11576.768464630828], "value": 9738.97534056166, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1262.7032647327876, 1504.3681653705444, 1652.1161178769362, 1766.5936834528065, 1842.6683197024531, 7983.640736166872, 8509.829803403929, 8606.311495080394, 9007.819843603133, 9042.773930651729, 8596.95701521493, 8776.385653004292, 8780.648774049047, 8787.384883058188, 8816.735497935073, 2362.2913126062153, 2407.5064611754638, 2409.795167410772, 2412.07135252928, 2418.6492958521003, 2041.2345370486069, 2361.4686695493547, 2398.9280321590295, 2417.685700858886, 2420.7095148582193, 1986.04669758516, 2398.8680622565753, 2399.460121472672, 2411.4574220800355, 2414.5653782319177, 1992.14337834731, 2318.7681231876845, 2380.73811880693, 2391.1152873436768, 2393.270052633937, 2381.6546600742968, 2382.988085059581, 2384.8926798293946, 2385.1294277588554, 2390.032037025008, 8786.121387861227, 8913.643454261823, 8940.195382415613, 8956.8656470153, 8963.641454341818, 5221.034108689904, 5245.79016839326, 5297.867553311159, 5360.9463905360835, 5382.407935127172, 3533.6819686228364, 3659.1766852457986, 3687.078230442389, 3741.56971948224, 3743.7192210584317, 878.96044677989, 1078.6224821312555, 1079.8272276435812, 1117.899389054981, 1135.9545618175264, 9735.17251033663, 10296.382217067, 11359.261647992855, 11482.311061336262, 11595.884041159565, 4014.237294913957, 4192.972721683146, 4546.977265113654, 4638.141943740419, 4825.95174048262, 9476.384035037701, 9893.307468477282, 9897.950510247403, 9903.504824758733, 9921.156701680293, 5299.973500132474, 5330.786768529216, 5381.730913454312, 5381.946180538144, 5395.3525576931015, 4095.774732389747, 4413.293872980308, 4760.904781904412, 4849.709017458964, 4926.0, 3882.2235552889533, 3907.081835768592, 3978.6618137458063, 4008.8396464141106, 4045.696572756996, 1294.6116165150418, 1302.2056545507232, 1303.2636560343376, 1313.875181857722, 1322.927239001864, 734.8273155808381, 858.6222062292571, 900.8333458310234, 910.5993362920294, 944.6693657220055, 5343.7862485500145, 5681.176229446707, 5792.710364481759, 5916.7337469813965, 6122.816315510542, 957.2007373842919, 957.5786653872414, 962.379265373844, 975.9609615615437, 1011.7116621762763, 3501.844391350848, 3592.586852511942, 3913.0, 3983.3029219886403, 4080.8571699990357, 10488.160947124212, 10790.73023174427, 10814.567417303377, 10821.026107650348, 10836.295640783324, 10670.51982660782, 10709.303895246783, 10754.677359679223, 10805.243632945618, 10806.891931080745, 7145.17830449505, 7803.453758236869, 8100.83798324042, 8164.101948785629, 8310.335173186131, 5331.960267747752, 5999.790007349722, 6026.216591843032, 6135.0, 6189.071639254148, 8439.324854011787, 9629.277804164856, 9995.450250236016, 10003.649872254728, 10101.69694909154, 10100.242481813746, 10123.088921997056, 10198.490075496047, 10253.12848407864, 10352.171826254033, 6487.3836985486305, 7505.512141710771, 7512.849743005218, 7573.697052117854, 7629.809254768453, 8169.428140030141, 8238.0, 8297.917020829957, 8310.501369917823, 8339.583020849055, 7323.157836848831, 7434.405247580073, 7442.665080071518, 7502.774916752506, 7563.054618172729, 6163.322034576104, 7252.601106939169, 7269.0186824777975, 7406.333429991327, 7471.850562988601, 6910.378065974087, 6936.306369363023, 6969.0, 7045.577265364094, 7052.471064670068, 5007.073691367109, 5076.0, 5076.949230507796, 5088.821891233789, 5148.794048238028, 4960.652754307308, 5008.098542262428, 5129.333186685707, 5196.0, 5245.554127899019], "value": 4567.334246336535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.3502896171866, 304.3168087643241, 310.56675937067786, 315.8941754512238, 317.1025996430103, 4994.726344782084, 5460.6177567570185, 5628.746706398211, 5729.770809167633, 5765.423457654228, 4413.47038355397, 5011.799528018884, 5527.668339899599, 5641.830745077655, 5645.633033852797, 3630.8910732677928, 3728.701703863698, 4170.103427763031, 4271.850485233017, 4295.735241216682, 3900.5514365847866, 4177.540470548237, 4183.142455796555, 4314.525402205755, 4316.7194132381455, 3951.8221680024326, 4151.522574903895, 4153.252414565377, 4158.482154013778, 4193.0, 4171.70798044134, 4177.0392809653795, 4184.4351012613215, 4213.30480470722, 4217.472815898012, 3177.5551422800827, 3604.3692353838237, 3613.49411082446, 3628.6008539060945, 3629.65518275766, 5061.962822306624, 5137.33214682089, 5304.20436934459, 5428.267183930158, 5456.753231827057, 4776.880577985544, 4784.784684689197, 4800.207965685658, 4801.399825021872, 4808.663393562452, 5366.624336296461, 5568.470995255444, 5875.148103524965, 5890.764369425261, 5934.406559344075, 3257.690519400653, 3941.724079314449, 3947.4276229946786, 3964.453862993435, 3972.7020473464318, 5971.373005834368, 6173.382661733835, 6206.193194884681, 6257.78097766576, 6288.213973253343, 3361.596608407007, 3506.8947931562093, 3988.920221595552, 3989.3218152913496, 4004.599540045972, 3415.0608582639475, 3913.3151698452657, 3927.1752931884052, 3989.1822176453757, 4034.5561988181275, 4711.199096153662, 4721.276733992068, 4748.525147485224, 4757.167495688241, 4767.284907263938, 5893.204417403681, 5980.910286345708, 5998.700064996733, 6002.759889604455, 6007.309159446632, 1236.6599185224136, 1370.184740079651, 1374.4639590559689, 1382.9861701382858, 1393.780442113151, 783.8164371798512, 783.824263604593, 784.811645205158, 826.557791581501, 855.8416692911707, 3877.86427475037, 3914.706397020235, 4221.282381995068, 4358.19882410488, 4667.066586682677, 4187.759548641462, 4274.487061552633, 5014.34813474246, 5112.642115051911, 5143.922841157386, 4211.168141858301, 4535.478419981679, 4620.0, 4667.109820522694, 4682.986315884203, 2138.6471232246818, 2259.9322020339414, 2611.908583199579, 2631.973680263211, 2717.728227177306, 5247.871707582812, 5278.7594915194895, 5285.868392664567, 5312.0, 5431.321084864392, 4433.4236549249035, 4514.074614703978, 5264.341957255343, 5282.415275417359, 5309.681419114865, 4948.529889660476, 5208.296879921238, 5231.712255825966, 5301.363836339664, 5340.305760251143, 4671.252599584051, 4902.039184326298, 5145.8936328689515, 5162.303089082926, 5201.7399130044105, 4361.890952726208, 4811.566958973709, 5047.848564543069, 5058.317127187783, 5117.5905927526455, 4457.621102206349, 5094.770735316845, 5160.071187186342, 5229.966619360837, 5303.867403314948, 3011.65365982906, 3085.2595377109783, 3158.652548219648, 3166.7624928130945, 3577.2130131371055, 4389.758563279051, 4647.395838540968, 4927.802887884444, 5019.548240658358, 5165.147750621256, 5710.2862142232225, 5727.341355744143, 5894.174815525744, 5947.553933454921, 6008.969955150196, 5323.494268044529, 5976.970115149396, 5984.281886173687, 5990.143055652761, 6001.819945401645, 4923.532264434873, 5118.3090282811345, 5486.643368181056, 5737.397573254748, 5805.477507024388, 5870.178175055581, 5918.911216331672, 5919.372172652383, 5963.880722385441, 6010.609310394811, 5442.319710036246, 5636.577256705681, 5797.76808927655, 5807.245058142584, 5812.273465816773], "value": 4011.081131430155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.5467893929432, 675.5946432140719, 724.9234886194001, 729.7482368582839, 740.4039748002856, 15160.166190859512, 15790.0, 15945.84054159458, 16083.919580402082, 16219.0, 14699.265036748173, 14837.406503739838, 14930.776038359407, 14990.925045374757, 15009.474668386609, 690.3924546399162, 866.2030931542964, 888.1473785166248, 888.9466632002101, 892.3887137310962, 860.9612567434449, 877.9868301975475, 877.9956100219522, 878.4948654523674, 881.2245224202693, 889.7241855024963, 927.1331305229598, 929.2333824594702, 929.809389075238, 930.5672862119113, 910.804177101923, 919.889613246408, 922.1976880114277, 923.3767207135157, 923.4044041593169, 680.4386381235452, 684.2336583026992, 687.3126873126873, 688.3770187979886, 688.8071340024801, 14999.775003374849, 15027.699446011127, 15053.849461505355, 15091.320890559951, 15113.848861511355, 11358.091352691765, 11834.585789497327, 14556.163230963315, 14593.27033648324, 14597.270136493133, 8720.607572659246, 9656.71029869105, 10210.336328138721, 11866.644000679993, 11879.168458207929, 3778.2065766188857, 3783.886483405502, 3804.3912973924316, 3832.9425058624142, 3921.0, 112316.43841780821, 127808.36095819554, 128352.0, 130845.69154308431, 133650.0, 9431.339806213637, 11083.038068043166, 11383.260088094248, 11408.657740267805, 11429.371384573928, 23189.0, 23208.53582928332, 23237.0, 23273.650895236584, 23334.766652333597, 7290.161631412349, 7723.150453450116, 7898.815177723387, 8083.514989100673, 8164.265216130458, 3865.9613403866165, 4418.955810441855, 4644.86065418038, 4668.5798278155125, 4792.0895029944195, 3507.15828201235, 3562.251927095287, 3613.674769270778, 3618.782873027627, 3624.5288112545727, 515.4536191636877, 568.7668056097064, 625.7090452939404, 642.6401215319436, 675.8918573028391, 516.5506009771523, 611.6635152192474, 629.6820105846518, 663.1875951958812, 683.8837397642412, 9346.439213647203, 9447.244220462484, 9492.193163581034, 9581.856272155923, 9633.951830240803, 407.97348172369294, 524.779592571121, 555.7977332170877, 613.6686189457643, 639.4326777986388, 3971.3645816669195, 4458.576435238647, 4678.087772884323, 4686.0, 4773.928391074067, 143082.0, 145462.0, 147247.2637636805, 148689.0, 148772.0, 87667.56166219128, 130524.0, 131667.34166329107, 133580.0, 137809.0, 107292.46353768182, 107924.0, 110661.0, 111692.88307116987, 112707.43646281716, 8180.918190818015, 8980.236679882022, 9037.909620903707, 9060.320475964461, 9122.452652840851, 47041.0, 59794.0, 60291.49271268078, 60351.39648603458, 61484.385156147866, 74804.0, 84083.0, 87339.0, 92716.0, 98483.50758246164, 11380.260283081574, 11453.713657158638, 11734.295942243492, 11957.0, 12153.210041347284, 12123.878761212274, 12381.071419643382, 12455.688607784878, 12555.43500542496, 12945.223286602833, 11606.30362178272, 12029.45867435985, 12177.02583793312, 12291.754164916472, 12406.255624662188, 11473.426328683365, 11539.307641541534, 11542.538298468304, 11586.88413115858, 11623.709407264549, 10552.944705529386, 12023.45894434735, 12085.697857553632, 12234.694132646755, 12345.506179752709, 9772.902270977484, 10142.442165680959, 10161.847572286273, 10186.643467478603, 10677.626283080055, 10115.79768404613, 10352.741181470523, 10470.0, 10538.262321637412, 10619.415932123498], "value": 7587.305080081414, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1649.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 145.0, 107.0, 95.0, 159.0, 155.0, 121.0, 107.0, 154.0, 193.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 127.0, 200.0, 126.0, 156.0, 118.0, 141.0, 197.0, 141.0], "value": 147.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [248.0, 259.0, 244.0, 270.0, 196.0, 197.0, 194.0, 197.0, 194.0, 198.0], "value": 219.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 83.0, 77.0, 153.0, 87.0, 84.0, 80.0, 61.0, 146.0, 86.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 120.0, 71.0, 69.0, 92.0, 111.0, 69.0, 74.0, 112.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 84.0, 91.0, 88.0, 93.0, 90.0, 91.0, 90.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 99.0, 96.0, 97.0, 96.0, 126.0, 109.0, 151.0, 114.0, 143.0], "value": 116.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 136.0, 148.0, 94.0, 93.0, 90.0, 92.0, 152.0, 154.0, 98.0], "value": 117.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 58.0, 58.0, 58.0, 56.0, 57.0, 57.0, 57.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 70.0, 75.0, 78.0, 63.0, 95.0, 103.0, 104.0, 102.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 88.0, 89.0, 90.0, 87.0, 86.0, 93.0, 93.0, 88.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 157.0, 141.0, 161.0, 162.0, 153.0, 143.0, 189.0, 142.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 232.0, 213.0, 175.0, 247.0, 185.0, 161.0, 175.0, 176.0, 143.0], "value": 192.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 70.0, 68.0, 67.0, 71.0, 69.0, 73.0, 92.0], "value": 71.3, "unit": "score"}]}]} 00:19:50 INFO - Return code: 0 00:19:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:19:50 ERROR - # TBPL SUCCESS # 00:19:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:19:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:19:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:19:50 INFO - 2016-09-04 00:19:50.392 screenresolution[1674:11036] starting screenresolution argv=screenresolution get 00:19:50 INFO - 2016-09-04 00:19:50.394 screenresolution[1674:11036] Display 0: 1600x1200x32@60 00:19:50 INFO - 2016-09-04 00:19:50.401 screenresolution[1675:11039] starting screenresolution argv=screenresolution list 00:19:50 INFO - Available Modes on Display 0 00:19:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:19:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:19:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:19:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:19:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:19:50 INFO - Intel Iris: 00:19:50 INFO - Chipset Model: Intel Iris 00:19:50 INFO - Type: GPU 00:19:50 INFO - Bus: Built-In 00:19:50 INFO - VRAM (Dynamic, Max): 1536 MB 00:19:50 INFO - Vendor: Intel (0x8086) 00:19:50 INFO - Device ID: 0x0a2e 00:19:50 INFO - Revision ID: 0x0009 00:19:50 INFO - Displays: 00:19:50 INFO - Display: 00:19:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:19:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:19:50 INFO - Display Serial Number: 1600x1200 60 00:19:50 INFO - Main Display: Yes 00:19:50 INFO - Mirror: Off 00:19:50 INFO - Online: Yes 00:19:50 INFO - Rotation: Supported 00:19:50 INFO - Return code: 0 00:19:50 INFO - Running post-action listener: _resource_record_post_action 00:19:50 INFO - [mozharness: 2016-09-04 07:19:50.644378Z] Finished run-tests step (success) 00:19:50 INFO - Running post-run listener: _resource_record_post_run 00:19:50 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 34417664; Write bytes: 705787392; Read time: 258; Write time: 2030 00:19:50 INFO - TinderboxPrint: CPU usage
54.6% 00:19:50 INFO - TinderboxPrint: I/O read bytes / time
34,417,664 / 258 00:19:50 INFO - TinderboxPrint: I/O write bytes / time
705,787,392 / 2,030 00:19:50 INFO - TinderboxPrint: CPU idle
2,196.4 (45.4%) 00:19:50 INFO - TinderboxPrint: CPU system
976.2 (20.2%) 00:19:50 INFO - TinderboxPrint: CPU user
1,669.2 (34.5%) 00:19:50 INFO - TinderboxPrint: Swap in / out
435,511,296 / 0 00:19:50 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390021632; Write bytes: 383219200; Read time: 29487; Write time: 791 00:19:50 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 33373184; Write bytes: 298344448; Read time: 243; Write time: 1181 00:19:51 INFO - Running post-run listener: _upload_blobber_files 00:19:51 INFO - Blob upload gear active. 00:19:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:19:51 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 00:19:51 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'] 00:19:51 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 00:19:51 INFO - (blobuploader) - INFO - Open directory for files ... 00:19:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:19:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:19:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:19:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:19:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:19:52 INFO - (blobuploader) - INFO - Done attempting. 00:19:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:19:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:19:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:19:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:19:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:19:54 INFO - (blobuploader) - INFO - Done attempting. 00:19:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:19:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:19:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:19:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:19:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:19:56 INFO - (blobuploader) - INFO - Done attempting. 00:19:56 INFO - (blobuploader) - INFO - Iteration through files over. 00:19:56 INFO - Return code: 0 00:19:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:19:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:19:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce40064da3c9c3eac3f7e0de8c17b3243155e0797d08787f5c2a1d26e8184983a2232b35c248f8cf1922f9b61b4f324b0d987d800f8acd763ff9f939d9bbb6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c89cafb64b51a0b566117afc6023d157bd12cf61e5b3e8d8b4add73ce05d2b35b15d4c2c41c265d9c5c7f1c057f6cbc1d107157677a96582fa8ab0f0464faea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4be71a27c6b7091ed7c6e4b3896ba65e7aeefc1c48d15b269748fabc8be2f55e68379da54e8255ec3e237e1e25a8e32eb97fcf4a72cfbada7d7b0ce7cfd4c026"} 00:19:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:19:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:19:56 INFO - Contents: 00:19:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce40064da3c9c3eac3f7e0de8c17b3243155e0797d08787f5c2a1d26e8184983a2232b35c248f8cf1922f9b61b4f324b0d987d800f8acd763ff9f939d9bbb6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c89cafb64b51a0b566117afc6023d157bd12cf61e5b3e8d8b4add73ce05d2b35b15d4c2c41c265d9c5c7f1c057f6cbc1d107157677a96582fa8ab0f0464faea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4be71a27c6b7091ed7c6e4b3896ba65e7aeefc1c48d15b269748fabc8be2f55e68379da54e8255ec3e237e1e25a8e32eb97fcf4a72cfbada7d7b0ce7cfd4c026"} 00:19:56 INFO - Running post-run listener: copy_logs_to_upload_dir 00:19:56 INFO - Copying logs to upload dir... 00:19:56 INFO - mkdir: /builds/slave/test/build/upload/logs 00:19:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.773045 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-04 00:19:56.906647) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 00:19:56.911918) ========= 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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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/ce40064da3c9c3eac3f7e0de8c17b3243155e0797d08787f5c2a1d26e8184983a2232b35c248f8cf1922f9b61b4f324b0d987d800f8acd763ff9f939d9bbb6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c89cafb64b51a0b566117afc6023d157bd12cf61e5b3e8d8b4add73ce05d2b35b15d4c2c41c265d9c5c7f1c057f6cbc1d107157677a96582fa8ab0f0464faea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4be71a27c6b7091ed7c6e4b3896ba65e7aeefc1c48d15b269748fabc8be2f55e68379da54e8255ec3e237e1e25a8e32eb97fcf4a72cfbada7d7b0ce7cfd4c026"} build_url:https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011039 build_url: 'https://queue.taskcluster.net/v1/task/dNmifxkdTtSL3z3Fo_oNhA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce40064da3c9c3eac3f7e0de8c17b3243155e0797d08787f5c2a1d26e8184983a2232b35c248f8cf1922f9b61b4f324b0d987d800f8acd763ff9f939d9bbb6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c89cafb64b51a0b566117afc6023d157bd12cf61e5b3e8d8b4add73ce05d2b35b15d4c2c41c265d9c5c7f1c057f6cbc1d107157677a96582fa8ab0f0464faea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4be71a27c6b7091ed7c6e4b3896ba65e7aeefc1c48d15b269748fabc8be2f55e68379da54e8255ec3e237e1e25a8e32eb97fcf4a72cfbada7d7b0ce7cfd4c026"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-04 00:19:56.944778) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 00:19:56.945121) ========= 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.nuoY8uDaE6/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.YoMyBE9F2X/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.005327 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-04 00:19:56.993049) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 00:19:56.993365) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-04 00:19:56.993685) ========= ========= Total master_lag: 3.13 =========