builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0283 starttime: 1472925876.16 results: success (0) buildid: 20160903081850 builduid: 2868bb92124e4b189d41aab81e0dfb5c revision: c6906f5fc27809e2775e88a2ccc88fb794c93f4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:36.164690) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 11:04:36.165190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 12 secs) (at 2016-09-03 11:04:36.165477) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 12 secs) (at 2016-09-03 11:04:48.418946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-09-03 11:04:48.419301) ========= 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.dzyvi8D8K2/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.izFB9BSXnV/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:04:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.41M=0.001s 2016-09-03 11:04:49 (8.41 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.561890 ========= master_lag: 4.61 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-09-03 11:04:53.591597) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-09-03 11:04:53.591915) ========= 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.dzyvi8D8K2/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.izFB9BSXnV/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.020369 ========= master_lag: 5.95 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-09-03 11:04:59.563478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 11:04:59.563901) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c6906f5fc27809e2775e88a2ccc88fb794c93f4d --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 c6906f5fc27809e2775e88a2ccc88fb794c93f4d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dzyvi8D8K2/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.izFB9BSXnV/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:00,112 truncating revision to first 12 chars 2016-09-03 11:05:00,113 Setting DEBUG logging. 2016-09-03 11:05:00,113 attempt 1/10 2016-09-03 11:05:00,113 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c6906f5fc278?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 11:05:00,850 unpacking tar archive at: mozilla-central-c6906f5fc278/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002658 ========= master_lag: 0.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 11:05:01.091488) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 11:05:01.091848) ========= script_repo_revision: c6906f5fc27809e2775e88a2ccc88fb794c93f4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 11:05:01.092298) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 11:05:01.092566) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 11:05:01.163777) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-03 11:05:01.164227) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.dzyvi8D8K2/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.izFB9BSXnV/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:01 INFO - MultiFileLogger online at 20160903 11:05:01 in /builds/slave/test 11:05:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:05:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:05:01 INFO - {'append_to_log': False, 11:05:01 INFO - 'base_work_dir': '/builds/slave/test', 11:05:01 INFO - 'blob_upload_branch': 'Firefox', 11:05:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:05:01 INFO - 'branch': 'Firefox', 11:05:01 INFO - 'buildbot_json_path': 'buildprops.json', 11:05:01 INFO - 'config_files': ('talos/mac_config.py',), 11:05:01 INFO - 'default_actions': ('clobber', 11:05:01 INFO - 'read-buildbot-config', 11:05:01 INFO - 'download-and-extract', 11:05:01 INFO - 'populate-webroot', 11:05:01 INFO - 'create-virtualenv', 11:05:01 INFO - 'install', 11:05:01 INFO - 'run-tests'), 11:05:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:05:01 INFO - 'download_minidump_stackwalk': True, 11:05:01 INFO - 'download_symbols': 'ondemand', 11:05:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:05:01 INFO - 'tooltool.py': '/tools/tooltool.py', 11:05:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:05:01 INFO - '/tools/misc-python/virtualenv.py')}, 11:05:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:05:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:05:01 INFO - 'installer_path': 'installer.exe', 11:05:01 INFO - 'log_level': 'info', 11:05:01 INFO - 'log_name': 'talos', 11:05:01 INFO - 'log_to_console': True, 11:05:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:05:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:05:01 INFO - 'opt_config_files': (), 11:05:01 INFO - 'pip_index': False, 11:05:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:05:01 INFO - 'cmd': ('bash', 11:05:01 INFO - '-c', 11:05:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:05:01 INFO - 'enabled': True, 11:05:01 INFO - 'halt_on_failure': False, 11:05:01 INFO - 'name': 'check_screen_resolution'},), 11:05:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:05:01 INFO - 'cmd': ('bash', 11:05:01 INFO - '-c', 11:05:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:05:01 INFO - 'enabled': True, 11:05:01 INFO - 'halt_on_failure': False, 11:05:01 INFO - 'name': 'check_screen_resolution'},), 11:05:01 INFO - 'run_cmd_checks_enabled': True, 11:05:01 INFO - 'sps_profile': False, 11:05:01 INFO - 'sps_profile_interval': 0, 11:05:01 INFO - 'suite': 'dromaeojs', 11:05:01 INFO - 'system_bits': '32', 11:05:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:05:01 INFO - 'title': 't-yosemite-r7-0283', 11:05:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:05:01 INFO - 'use_talos_json': True, 11:05:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:05:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:05:01 INFO - 'work_dir': 'build'} 11:05:01 INFO - [mozharness: 2016-09-03 18:05:01.364223Z] Running clobber step. 11:05:01 INFO - Running pre-action listener: _resource_record_pre_action 11:05:01 INFO - Running main action method: clobber 11:05:01 INFO - rmtree: /builds/slave/test/build 11:05:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:05:02 INFO - Running post-action listener: _resource_record_post_action 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.246767Z] Finished clobber step (success) 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.246935Z] Running read-buildbot-config step. 11:05:02 INFO - Running pre-action listener: _resource_record_pre_action 11:05:02 INFO - Running main action method: read_buildbot_config 11:05:02 INFO - Using buildbot properties: 11:05:02 INFO - { 11:05:02 INFO - "project": "", 11:05:02 INFO - "product": "firefox", 11:05:02 INFO - "script_repo_revision": "production", 11:05:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:05:02 INFO - "repository": "", 11:05:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:05:02 INFO - "buildid": "20160903081850", 11:05:02 INFO - "pgo_build": "False", 11:05:02 INFO - "basedir": "/builds/slave/test", 11:05:02 INFO - "buildnumber": 95, 11:05:02 INFO - "slavename": "t-yosemite-r7-0283", 11:05:02 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:05:02 INFO - "platform": "yosemite_r7", 11:05:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:05:02 INFO - "branch": "mozilla-central", 11:05:02 INFO - "repo_path": "mozilla-central", 11:05:02 INFO - "revision": "c6906f5fc27809e2775e88a2ccc88fb794c93f4d", 11:05:02 INFO - "stage_platform": "macosx64", 11:05:02 INFO - "builduid": "2868bb92124e4b189d41aab81e0dfb5c", 11:05:02 INFO - "slavebuilddir": "test" 11:05:02 INFO - } 11:05:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:05:02 INFO - Running post-action listener: _resource_record_post_action 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.250282Z] Finished read-buildbot-config step (success) 11:05:02 INFO - [mozharness: 2016-09-03 18:05:02.250388Z] Running download-and-extract step. 11:05:02 INFO - Running pre-action listener: _resource_record_pre_action 11:05:02 INFO - Running main action method: download_and_extract 11:05:02 INFO - mkdir: /builds/slave/test/build/tests 11:05:02 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')]} 11:05:02 INFO - trying https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:05:02 INFO - Downloading https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/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 11:05:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/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 11:05:03 INFO - Downloaded 1199 bytes. 11:05:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:05:03 INFO - Using the following test package requirements: 11:05:03 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:05:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:05:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:05:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'jsshell-mac.zip'], 11:05:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:05:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:05:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:05:03 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:05:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:05:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:05:03 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:05:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:05:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:05:03 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:05:10 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 11:05:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:05:11 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:05:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:05:18 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 11:05:19 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')]} 11:05:19 INFO - trying https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:05:19 INFO - Downloading https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:05:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/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 11:05:22 INFO - Downloaded 111484377 bytes. 11:05:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:05:22 INFO - mkdir: /builds/slave/test/properties 11:05:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:05:22 INFO - Writing to file /builds/slave/test/properties/build_url 11:05:22 INFO - Contents: 11:05:22 INFO - build_url:https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:05:22 INFO - Running post-action listener: _resource_record_post_action 11:05:22 INFO - Running post-action listener: set_extra_try_arguments 11:05:22 INFO - [mozharness: 2016-09-03 18:05:22.904451Z] Finished download-and-extract step (success) 11:05:22 INFO - [mozharness: 2016-09-03 18:05:22.904569Z] Running populate-webroot step. 11:05:22 INFO - Running pre-action listener: _resource_record_pre_action 11:05:22 INFO - Running main action method: populate_webroot 11:05:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:05:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:05:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:05:22 INFO - '--tppagecycles', 11:05:22 INFO - '7'], 11:05:22 INFO - 'tests': ['tsvgm']}}, 11:05:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:05:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:05:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:05:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:05:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:05:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:05:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['damp', 'tps']}, 11:05:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'tests': ['damp', 'tps']}, 11:05:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['dromaeo_dom']}, 11:05:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:05:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['basic_compositor_video']}, 11:05:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:05:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['a11yr', 11:05:22 INFO - 'ts_paint', 11:05:22 INFO - 'tpaint', 11:05:22 INFO - 'sessionrestore', 11:05:22 INFO - 'sessionrestore_no_auto_restore', 11:05:22 INFO - 'tabpaint']}, 11:05:22 INFO - 'other-e10s': {'tests': ['a11yr', 11:05:22 INFO - 'ts_paint', 11:05:22 INFO - 'tpaint', 11:05:22 INFO - 'sessionrestore', 11:05:22 INFO - 'sessionrestore_no_auto_restore', 11:05:22 INFO - 'tabpaint']}, 11:05:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:05:22 INFO - 'ts_paint', 11:05:22 INFO - 'tpaint', 11:05:22 INFO - 'sessionrestore', 11:05:22 INFO - 'sessionrestore_no_auto_restore', 11:05:22 INFO - 'tabpaint']}, 11:05:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:05:22 INFO - 'ts_paint', 11:05:22 INFO - 'tpaint', 11:05:22 INFO - 'sessionrestore', 11:05:22 INFO - 'sessionrestore_no_auto_restore', 11:05:22 INFO - 'tabpaint']}, 11:05:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['a11yr', 11:05:22 INFO - 'ts_paint', 11:05:22 INFO - 'tpaint', 11:05:22 INFO - 'sessionrestore', 11:05:22 INFO - 'sessionrestore_no_auto_restore', 11:05:22 INFO - 'tabpaint']}, 11:05:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['a11yr', 11:05:22 INFO - 'ts_paint', 11:05:22 INFO - 'tpaint', 11:05:22 INFO - 'sessionrestore', 11:05:22 INFO - 'sessionrestore_no_auto_restore', 11:05:22 INFO - 'tabpaint']}, 11:05:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['tsvgx', 11:05:22 INFO - 'tsvgr_opacity', 11:05:22 INFO - 'tart', 11:05:22 INFO - 'tscrollx', 11:05:22 INFO - 'cart']}, 11:05:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:05:22 INFO - 'tsvgr_opacity', 11:05:22 INFO - 'tart', 11:05:22 INFO - 'tscrollx', 11:05:22 INFO - 'cart']}, 11:05:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'talos_options': ['--disable-e10s'], 11:05:22 INFO - 'tests': ['tp5o']}, 11:05:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'tests': ['tp5o']}, 11:05:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'talos_options': ['--disable-e10s', 11:05:22 INFO - '--xperf_path', 11:05:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:05:22 INFO - 'tests': ['tp5n']}, 11:05:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:22 INFO - 'talos_options': ['--xperf_path', 11:05:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:05:22 INFO - 'tests': ['tp5n']}}, 11:05:22 INFO - 'talos.zip': {'path': '', 11:05:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:05:22 INFO - Running post-action listener: _resource_record_post_action 11:05:22 INFO - [mozharness: 2016-09-03 18:05:22.923352Z] Finished populate-webroot step (success) 11:05:22 INFO - [mozharness: 2016-09-03 18:05:22.923483Z] Running create-virtualenv step. 11:05:22 INFO - Running pre-action listener: _resource_record_pre_action 11:05:22 INFO - Running main action method: create_virtualenv 11:05:22 INFO - Creating virtualenv /builds/slave/test/build/venv 11:05:22 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 11:05:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:05:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:05:23 INFO - Using real prefix '/tools/python27' 11:05:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:05:23 INFO - Installing distribute.............................................................................................................................................................................................done. 11:05:26 INFO - Installing pip.................done. 11:05:26 INFO - Return code: 0 11:05:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:05:26 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')]} 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:26 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')]} 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:26 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:26 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 11:05:26 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 11:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:26 INFO - 'HOME': '/Users/cltbld', 11:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:26 INFO - 'LOGNAME': 'cltbld', 11:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:26 INFO - 'MOZ_NO_REMOTE': '1', 11:05:26 INFO - 'NO_EM_RESTART': '1', 11:05:26 INFO - 'PAGER': '/bin/cat', 11:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:26 INFO - 'PWD': '/builds/slave/test', 11:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:26 INFO - 'SHELL': '/bin/bash', 11:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:26 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:26 INFO - 'USER': 'cltbld', 11:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:26 INFO - 'XPC_FLAGS': '0x0', 11:05:26 INFO - 'XPC_SERVICE_NAME': '0', 11:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:26 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 11:05:26 INFO - Cleaning up... 11:05:27 INFO - Return code: 0 11:05:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:27 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:27 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 11:05:27 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 11:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:27 INFO - 'HOME': '/Users/cltbld', 11:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:27 INFO - 'LOGNAME': 'cltbld', 11:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:27 INFO - 'MOZ_NO_REMOTE': '1', 11:05:27 INFO - 'NO_EM_RESTART': '1', 11:05:27 INFO - 'PAGER': '/bin/cat', 11:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:27 INFO - 'PWD': '/builds/slave/test', 11:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:27 INFO - 'SHELL': '/bin/bash', 11:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:27 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:27 INFO - 'USER': 'cltbld', 11:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:27 INFO - 'XPC_FLAGS': '0x0', 11:05:27 INFO - 'XPC_SERVICE_NAME': '0', 11:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:27 INFO - Downloading/unpacking psutil>=3.1.1 11:05: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 11:05: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 11:05: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 11:05: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 11:05: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 11:05: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 11:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:05:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:31 INFO - Installing collected packages: psutil 11:05:31 INFO - Running setup.py install for psutil 11:05:31 INFO - building 'psutil._psutil_osx' extension 11:05:31 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 11:05:31 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 11:05:31 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 11:05:31 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 11:05:31 INFO - building 'psutil._psutil_posix' extension 11:05:31 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 11:05:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:05:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:05:31 INFO - ^ 11:05:31 INFO - 1 warning generated. 11:05:31 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 11:05:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:31 INFO - Successfully installed psutil 11:05:31 INFO - Cleaning up... 11:05:31 INFO - Return code: 0 11:05:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:05:31 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')]} 11:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:31 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')]} 11:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:31 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:31 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 11:05:31 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 11:05:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:31 INFO - 'HOME': '/Users/cltbld', 11:05:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:31 INFO - 'LOGNAME': 'cltbld', 11:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:31 INFO - 'MOZ_NO_REMOTE': '1', 11:05:31 INFO - 'NO_EM_RESTART': '1', 11:05:31 INFO - 'PAGER': '/bin/cat', 11:05:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:31 INFO - 'PWD': '/builds/slave/test', 11:05:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:31 INFO - 'SHELL': '/bin/bash', 11:05:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:31 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:31 INFO - 'USER': 'cltbld', 11:05:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:31 INFO - 'XPC_FLAGS': '0x0', 11:05:31 INFO - 'XPC_SERVICE_NAME': '0', 11:05:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:05:32 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 11:05:32 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 11:05:32 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 11:05:32 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 11:05:32 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 11:05:32 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 11:05:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:05:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:05:34 INFO - Installing collected packages: mozsystemmonitor 11:05:34 INFO - Running setup.py install for mozsystemmonitor 11:05:34 INFO - Successfully installed mozsystemmonitor 11:05:34 INFO - Cleaning up... 11:05:34 INFO - Return code: 0 11:05:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:05:34 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')]} 11:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:34 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')]} 11:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:34 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:34 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 11:05:34 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 11:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:34 INFO - 'HOME': '/Users/cltbld', 11:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:34 INFO - 'LOGNAME': 'cltbld', 11:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:34 INFO - 'MOZ_NO_REMOTE': '1', 11:05:34 INFO - 'NO_EM_RESTART': '1', 11:05:34 INFO - 'PAGER': '/bin/cat', 11:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:34 INFO - 'PWD': '/builds/slave/test', 11:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:34 INFO - 'SHELL': '/bin/bash', 11:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:34 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:34 INFO - 'USER': 'cltbld', 11:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:34 INFO - 'XPC_FLAGS': '0x0', 11:05:34 INFO - 'XPC_SERVICE_NAME': '0', 11:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:35 INFO - Downloading/unpacking jsonschema==2.5.1 11:05: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 11:05: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 11:05: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 11:05: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 11:05: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 11:05: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 11:05:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:05:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:37 INFO - Downloading functools32-3.2.3-2.tar.gz 11:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:05:38 INFO - warning: no files found matching '*.txt' 11:05:38 INFO - no previously-included directories found matching 'build' 11:05:38 INFO - no previously-included directories found matching 'dist' 11:05:38 INFO - no previously-included directories found matching '.git*' 11:05:38 INFO - Installing collected packages: jsonschema, functools32 11:05:38 INFO - Running setup.py install for functools32 11:05:38 INFO - warning: no files found matching '*.txt' 11:05:38 INFO - no previously-included directories found matching 'build' 11:05:38 INFO - no previously-included directories found matching 'dist' 11:05:38 INFO - no previously-included directories found matching '.git*' 11:05:38 INFO - Successfully installed jsonschema functools32 11:05:38 INFO - Cleaning up... 11:05:38 INFO - Return code: 0 11:05:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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', '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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:05: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 functools32==3.2.3-2 11:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:38 INFO - 'HOME': '/Users/cltbld', 11:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:38 INFO - 'LOGNAME': 'cltbld', 11:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:38 INFO - 'MOZ_NO_REMOTE': '1', 11:05:38 INFO - 'NO_EM_RESTART': '1', 11:05:38 INFO - 'PAGER': '/bin/cat', 11:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:38 INFO - 'PWD': '/builds/slave/test', 11:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:38 INFO - 'SHELL': '/bin/bash', 11:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:38 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:38 INFO - 'USER': 'cltbld', 11:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:38 INFO - 'XPC_FLAGS': '0x0', 11:05:38 INFO - 'XPC_SERVICE_NAME': '0', 11:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:05:38 INFO - Cleaning up... 11:05:38 INFO - Return code: 0 11:05:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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', '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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:05: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 blobuploader==1.2.4 11:05:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:38 INFO - 'HOME': '/Users/cltbld', 11:05:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:38 INFO - 'LOGNAME': 'cltbld', 11:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:38 INFO - 'MOZ_NO_REMOTE': '1', 11:05:38 INFO - 'NO_EM_RESTART': '1', 11:05:38 INFO - 'PAGER': '/bin/cat', 11:05:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:38 INFO - 'PWD': '/builds/slave/test', 11:05:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:38 INFO - 'SHELL': '/bin/bash', 11:05:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:38 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:38 INFO - 'USER': 'cltbld', 11:05:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:38 INFO - 'XPC_FLAGS': '0x0', 11:05:38 INFO - 'XPC_SERVICE_NAME': '0', 11:05:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:38 INFO - Downloading/unpacking blobuploader==1.2.4 11:05:38 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 11:05:38 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 11:05:38 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 11:05:38 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 11:05:38 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 11:05:38 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 11:05:41 INFO - Downloading blobuploader-1.2.4.tar.gz 11:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:05:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:05:41 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 11:05:41 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 11:05:41 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 11:05:41 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 11:05:41 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 11:05:41 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 11:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:05:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:42 INFO - Downloading docopt-0.6.1.tar.gz 11:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:05:42 INFO - Installing collected packages: blobuploader, requests, docopt 11:05:42 INFO - Running setup.py install for blobuploader 11:05:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:05:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:05:42 INFO - Running setup.py install for requests 11:05:43 INFO - Running setup.py install for docopt 11:05:43 INFO - Successfully installed blobuploader requests docopt 11:05:43 INFO - Cleaning up... 11:05:43 INFO - Return code: 0 11:05:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:43 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')]} 11:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:43 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')]} 11:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:43 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:43 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 11:05:43 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 11:05:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:43 INFO - 'HOME': '/Users/cltbld', 11:05:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:43 INFO - 'LOGNAME': 'cltbld', 11:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:43 INFO - 'MOZ_NO_REMOTE': '1', 11:05:43 INFO - 'NO_EM_RESTART': '1', 11:05:43 INFO - 'PAGER': '/bin/cat', 11:05:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:43 INFO - 'PWD': '/builds/slave/test', 11:05:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:43 INFO - 'SHELL': '/bin/bash', 11:05:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:43 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:43 INFO - 'USER': 'cltbld', 11:05:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:43 INFO - 'XPC_FLAGS': '0x0', 11:05:43 INFO - 'XPC_SERVICE_NAME': '0', 11:05:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-5ZKRY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:43 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-_il1ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-W6M_xY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-L_97zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-T93xdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-kAtxfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Z8kFUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-cU8wY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Wu6qHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-MPmS7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-jyQmq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:44 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-JjjV7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:45 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-jiFONy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:45 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-leKTai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:45 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-sG4tyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:45 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-pRhvqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:45 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-Nkae3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:05:45 INFO - Running setup.py install for manifestparser 11:05:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:05:45 INFO - Running setup.py install for mozcrash 11:05:45 INFO - Running setup.py install for mozdebug 11:05:46 INFO - Running setup.py install for mozdevice 11:05:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Running setup.py install for mozfile 11:05:46 INFO - Running setup.py install for mozhttpd 11:05:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Running setup.py install for mozinfo 11:05:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Running setup.py install for mozInstall 11:05:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:05:46 INFO - Running setup.py install for mozleak 11:05:46 INFO - Running setup.py install for mozlog 11:05:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:05:47 INFO - Running setup.py install for moznetwork 11:05:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:05:47 INFO - Running setup.py install for mozprocess 11:05:47 INFO - Running setup.py install for mozprofile 11:05:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:05:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:05:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:05:47 INFO - Running setup.py install for mozrunner 11:05:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:05:47 INFO - Running setup.py install for mozscreenshot 11:05:47 INFO - Running setup.py install for moztest 11:05:48 INFO - Running setup.py install for mozversion 11:05:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:05:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:05:48 INFO - Cleaning up... 11:05:48 INFO - Return code: 0 11:05:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:48 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')]} 11:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:48 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')]} 11:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:48 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:48 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 11:05:48 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 11:05:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:48 INFO - 'HOME': '/Users/cltbld', 11:05:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:48 INFO - 'LOGNAME': 'cltbld', 11:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:48 INFO - 'MOZ_NO_REMOTE': '1', 11:05:48 INFO - 'NO_EM_RESTART': '1', 11:05:48 INFO - 'PAGER': '/bin/cat', 11:05:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:48 INFO - 'PWD': '/builds/slave/test', 11:05:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:48 INFO - 'SHELL': '/bin/bash', 11:05:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:48 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:48 INFO - 'USER': 'cltbld', 11:05:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:48 INFO - 'XPC_FLAGS': '0x0', 11:05:48 INFO - 'XPC_SERVICE_NAME': '0', 11:05:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:48 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-TqNq0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:48 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)) 11:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:48 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-de6_Bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:48 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)) 11:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:48 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-8W2Lf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:48 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)) 11:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:48 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-_PByEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:48 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)) 11:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:48 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-91NPlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:48 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)) 11:05:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:48 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-t5bj2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-H3onbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-cUhED0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-rdVjYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-YOCiqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-sU4ktY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-i_DSFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-2MpxqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-IhCbLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:49 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)) 11:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:49 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-A3iULl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:50 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)) 11:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:50 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-DC4AhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:50 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)) 11:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:50 INFO - Running setup.py (path:/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/pip-ckjCe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:50 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)) 11:05:50 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)) 11:05:50 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)) 11:05:50 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)) 11:05:50 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)) 11:05:50 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)) 11:05:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:52 INFO - Downloading blessings-1.6.tar.gz 11:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:05:52 INFO - Installing collected packages: blessings 11:05:52 INFO - Running setup.py install for blessings 11:05:52 INFO - Successfully installed blessings 11:05:52 INFO - Cleaning up... 11:05:53 INFO - Return code: 0 11:05:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:05:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:53 INFO - Reading from file tmpfile_stdout 11:05:53 INFO - Current package versions: 11:05:53 INFO - blessings == 1.6 11:05:53 INFO - blobuploader == 1.2.4 11:05:53 INFO - docopt == 0.6.1 11:05:53 INFO - functools32 == 3.2.3-2 11:05:53 INFO - jsonschema == 2.5.1 11:05:53 INFO - manifestparser == 1.1 11:05:53 INFO - mozInstall == 1.12 11:05:53 INFO - mozcrash == 0.17 11:05:53 INFO - mozdebug == 0.1 11:05:53 INFO - mozdevice == 0.48 11:05:53 INFO - mozfile == 1.2 11:05:53 INFO - mozhttpd == 0.7 11:05:53 INFO - mozinfo == 0.9 11:05:53 INFO - mozleak == 0.1 11:05:53 INFO - mozlog == 3.3 11:05:53 INFO - moznetwork == 0.27 11:05:53 INFO - mozprocess == 0.23 11:05:53 INFO - mozprofile == 0.28 11:05:53 INFO - mozrunner == 6.12 11:05:53 INFO - mozscreenshot == 0.1 11:05:53 INFO - mozsystemmonitor == 0.3 11:05:53 INFO - moztest == 0.7 11:05:53 INFO - mozversion == 1.4 11:05:53 INFO - psutil == 3.1.1 11:05:53 INFO - requests == 1.2.3 11:05:53 INFO - wsgiref == 0.1.2 11:05:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:53 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 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:53 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 11:05:53 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 11:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:53 INFO - 'HOME': '/Users/cltbld', 11:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:53 INFO - 'LOGNAME': 'cltbld', 11:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:53 INFO - 'MOZ_NO_REMOTE': '1', 11:05:53 INFO - 'NO_EM_RESTART': '1', 11:05:53 INFO - 'PAGER': '/bin/cat', 11:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:53 INFO - 'PWD': '/builds/slave/test', 11:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:53 INFO - 'SHELL': '/bin/bash', 11:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:53 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:53 INFO - 'USER': 'cltbld', 11:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:53 INFO - 'XPC_FLAGS': '0x0', 11:05:53 INFO - 'XPC_SERVICE_NAME': '0', 11:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 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)) 11:05:53 INFO - Cleaning up... 11:05:53 INFO - Return code: 0 11:05:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1080ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3ea570cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, '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.dzyvi8D8K2/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.izFB9BSXnV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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 11:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:53 INFO - 'HOME': '/Users/cltbld', 11:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:53 INFO - 'LOGNAME': 'cltbld', 11:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:53 INFO - 'MOZ_NO_REMOTE': '1', 11:05:53 INFO - 'NO_EM_RESTART': '1', 11:05:53 INFO - 'PAGER': '/bin/cat', 11:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:53 INFO - 'PWD': '/builds/slave/test', 11:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:53 INFO - 'SHELL': '/bin/bash', 11:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:05:53 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:53 INFO - 'USER': 'cltbld', 11:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:53 INFO - 'XPC_FLAGS': '0x0', 11:05:53 INFO - 'XPC_SERVICE_NAME': '0', 11:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:05:53 INFO - Cleaning up... 11:05:53 INFO - Return code: 0 11:05:53 INFO - Running post-action listener: _resource_record_post_action 11:05:53 INFO - Running post-action listener: _start_resource_monitoring 11:05:53 INFO - Starting resource monitoring. 11:05:53 INFO - [mozharness: 2016-09-03 18:05:53.932195Z] Finished create-virtualenv step (success) 11:05:53 INFO - [mozharness: 2016-09-03 18:05:53.933217Z] Running install step. 11:05:53 INFO - Running pre-action listener: _resource_record_pre_action 11:05:53 INFO - Running main action method: install 11:05:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:54 INFO - Reading from file tmpfile_stdout 11:05:54 INFO - Detecting whether we're running mozinstall >=1.0... 11:05:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:05:54 INFO - Reading from file tmpfile_stdout 11:05:54 INFO - Output received: 11:05:54 INFO - Usage: mozinstall [options] installer 11:05:54 INFO - Options: 11:05:54 INFO - -h, --help show this help message and exit 11:05:54 INFO - -d DEST, --destination=DEST 11:05:54 INFO - Directory to install application into. [default: 11:05:54 INFO - "/builds/slave/test"] 11:05:54 INFO - --app=APP Application being installed. [default: firefox] 11:05:54 INFO - mkdir: /builds/slave/test/build/application 11:05:54 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'] 11:05:54 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 11:06:28 INFO - Reading from file tmpfile_stdout 11:06:28 INFO - Output received: 11:06:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:06:28 INFO - Running post-action listener: _resource_record_post_action 11:06:28 INFO - [mozharness: 2016-09-03 18:06:28.670048Z] Finished install step (success) 11:06:28 INFO - [mozharness: 2016-09-03 18:06:28.670196Z] Running run-tests step. 11:06:28 INFO - Running pre-action listener: _resource_record_pre_action 11:06:28 INFO - Running main action method: run_tests 11:06:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:06:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:06:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:06:28 INFO - 2016-09-03 11:06:28.684 screenresolution[1996:16301] starting screenresolution argv=screenresolution get 11:06:28 INFO - 2016-09-03 11:06:28.687 screenresolution[1996:16301] Display 0: 1600x1200x32@60 11:06:28 INFO - 2016-09-03 11:06:28.693 screenresolution[1997:16303] starting screenresolution argv=screenresolution list 11:06:28 INFO - Available Modes on Display 0 11:06:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:06:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:06:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:06:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:06:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:06:28 INFO - Intel Iris: 11:06:28 INFO - Chipset Model: Intel Iris 11:06:28 INFO - Type: GPU 11:06:28 INFO - Bus: Built-In 11:06:28 INFO - VRAM (Dynamic, Max): 1536 MB 11:06:28 INFO - Vendor: Intel (0x8086) 11:06:28 INFO - Device ID: 0x0a2e 11:06:28 INFO - Revision ID: 0x0009 11:06:28 INFO - Displays: 11:06:28 INFO - Display: 11:06:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:06:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:06:28 INFO - Display Serial Number: 1600x1200 60 11:06:28 INFO - Main Display: Yes 11:06:28 INFO - Mirror: Off 11:06:28 INFO - Online: Yes 11:06:28 INFO - Rotation: Supported 11:06:28 INFO - Return code: 0 11:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:06:28 INFO - Python 2.7.3 11:06:28 INFO - Return code: 0 11:06:28 INFO - grabbing minidump binary from tooltool 11:06: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')]} 11:06:28 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 0x108063d30>, '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 0x107dc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10805bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:06:28 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 11:06:28 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 11:06:28 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 11:06:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:06:29 INFO - Return code: 0 11:06:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:06:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:06:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:06:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:06:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:06:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:06:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0283 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dzyvi8D8K2/Render', 11:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:06:29 INFO - 'HOME': '/Users/cltbld', 11:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:06:29 INFO - 'LOGNAME': 'cltbld', 11:06:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:06:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:29 INFO - 'MOZ_NO_REMOTE': '1', 11:06:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:06:29 INFO - 'NO_EM_RESTART': '1', 11:06:29 INFO - 'PAGER': '/bin/cat', 11:06:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:29 INFO - 'PWD': '/builds/slave/test', 11:06:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:06:29 INFO - 'SHELL': '/bin/bash', 11:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izFB9BSXnV/Listeners', 11:06:29 INFO - 'TMPDIR': '/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/', 11:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:06:29 INFO - 'USER': 'cltbld', 11:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:29 INFO - 'XPC_FLAGS': '0x0', 11:06:29 INFO - 'XPC_SERVICE_NAME': '0', 11:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:06:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0283', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:06:29 INFO - mozversion application_buildid: 20160903081850 11:06:29 INFO - mozversion application_changeset: c6906f5fc27809e2775e88a2ccc88fb794c93f4d 11:06:29 INFO - mozversion application_display_name: Nightly 11:06:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:06:29 INFO - mozversion application_name: Firefox 11:06:29 INFO - mozversion application_remotingname: firefox 11:06:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:06:29 INFO - mozversion application_vendor: Mozilla 11:06:29 INFO - mozversion application_version: 51.0a1 11:06:29 INFO - mozversion platform_buildid: 20160903081850 11:06:29 INFO - mozversion platform_changeset: c6906f5fc27809e2775e88a2ccc88fb794c93f4d 11:06:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:06:29 INFO - mozversion platform_version: 51.0a1 11:06:29 INFO - using testdate: 1472925989 11:06:29 INFO - actual date: 1472925989 11:06:29 INFO - starting webserver on 'localhost:49705' 11:06:29 INFO - SUITE-START | Running 2 tests 11:06:29 INFO - TEST-START | dromaeo_css 11:06:29 INFO - operating with platform_type : mac_ 11:06:29 INFO - Initialising browser for dromaeo_css test... 11:06:29 INFO - TEST-INFO | started process 2007 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp9VYihj/profile http://localhost:49705/getInfo.html) 11:06:31 INFO - PROCESS | 2007 | __metrics Screen width/height:1600/1200 11:06:31 INFO - PROCESS | 2007 | colorDepth:24 11:06:31 INFO - PROCESS | 2007 | Browser inner width/height: 1024/689 11:06:31 INFO - PROCESS | 2007 | __metrics 11:06:32 INFO - PROCESS | 2007 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:06:32 INFO - PROCESS | 2007 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:06:32 INFO - TEST-INFO | 2007: exit 0 11:06:32 INFO - Browser initialized. 11:06:32 INFO - Running cycle 1/1 for dromaeo_css test... 11:06:32 INFO - TEST-INFO | started process 2009 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmp9VYihj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:06:44 INFO - PROCESS | 2009 | RSS: Main: 233799680 11:06:44 INFO - PROCESS | 2009 | 11:07:35 INFO - PROCESS | 2009 | 1472926055717 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] 11:09:47 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49705/tests/dromaeo/cssquery-ext.html) 11:09:48 INFO - PROCESS | 2009 | RSS: Main: 352727040 11:09:48 INFO - PROCESS | 2009 | 11:12:51 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-ext.html (next: http://localhost:49705/tests/dromaeo/cssquery-jquery.html) 11:12:52 INFO - PROCESS | 2009 | RSS: Main: 372686848 11:12:52 INFO - PROCESS | 2009 | 11:15:54 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49705/tests/dromaeo/cssquery-mootools.html) 11:15:55 INFO - PROCESS | 2009 | RSS: Main: 339562496 11:15:55 INFO - PROCESS | 2009 | 11:18:57 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49705/tests/dromaeo/cssquery-prototype.html) 11:18:58 INFO - PROCESS | 2009 | RSS: Main: 356388864 11:18:58 INFO - PROCESS | 2009 | 11:22:01 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49705/tests/dromaeo/cssquery-yui.html) 11:22:02 INFO - PROCESS | 2009 | RSS: Main: 331337728 11:22:02 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | Cycle 1(1): loaded http://localhost:49705/tests/dromaeo/cssquery-yui.html (next: http://localhost:49705/tests/dromaeo/cssquery-dojo.html) 11:25:05 INFO - PROCESS | 2009 | RSS: Main: 378281984 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | __start_tp_report 11:25:05 INFO - PROCESS | 2009 | _x_x_mozilla_page_load 11:25:05 INFO - PROCESS | 2009 | _x_x_mozilla_page_load_details 11:25:05 INFO - PROCESS | 2009 | |i|pagename|runs| 11:25:05 INFO - PROCESS | 2009 | |0;dojo.html;3219.050380137859;3229.3864165808495;3257.511373294007;3268.526063720762;3318.4026875162463;5375.75809088591;5401.594880383967;5467.589930755199;5474.343078830536;5499.58753093518;4491.719859839946;5026.0701770172545;5456.727163641812;5458.0721277382845;5460.153676180198;4118.629323360897;4971.676841005323;5021.196608542634;5051.06555287273;5103.515166059218;5007.549320561149;5107.744612769366;5108;5108.361454818148;5110.003549307887;4999.500049995007;5043.747812609373;5061.240813877929;5065.062963351773;5073.441921388644;5085.644004919657;5091.338126043628;5103.948960510422;5107.3105130807235;5124.615653825978;4901.313816065754;4938.851834444937;4950.87622809429;5029.823956161516;5049.141645920203;5447.5641948644015;5473.644213126134;5495.093309603911;5496.1755736639425;5534.944650553483;5969.134475501028;5974.581779275452;5993.460588547008;5996.670183139926;6004.639721616717;5762.711864406805;6035.336113027563;6049.425304596057;6052.182955301066;6082.118092876508;5576.776928922879;5589.80435684749;5596.272484576979;5602.635828671123;5649.209110724501;6196.597221180609;6255.812325630239;6262.436380725665;6271.96864015677;6273.498120150377;5749.252597162342;5802.738876750556;5821.301443826768;5903.0260007099205;5942.554308426886;6014.939850601438;6029.155918171459;6058.515318774487;6067.24159480065;6088.939110608926;5894.351621321651;5969.343372229051;5981.162637230791;5983.910241346383;5984.461398474158;4981.875453113701;5737.713114344266;5783.987802134694;5810.360860305363;5999.490043346277;4723.385959825201;4752.7623618818925;4757.976210118927;4761.4524329701835;4770.451398089264;4284.67864910133;4307.913841723211;4313;4344.478662560513;4368.301071828492;5540.418256083134;5540.944590554042;5548.361938377057;5552.666839989613;5585.106382978704;5949.732262048218;6073.878522429615;6101.633901965896;6102.603330783485;6151.98492248774;5503;5510.228568000483;5564.666120032811;5564.721763911788;5616.5787565931905;5251.212318152231;5265.76304066318;5265.894682106336;5275.155975043975;5280.709560974184;6300.747970081329;6303.24528452296;6314.62112273265;6330.8417289567205;6336.778212762531;5879.706014699163;5940.108983652487;6035.064564992434;6075.483583895419;6084.574079814371;6329.651869147241;6332.905006425;6334.018227174611;6354.872902541831;6360.173177486897;5906.881862362815;5925.703714814328;5933.317668468181;5943.613665111754;5944.601919538446;5869;6000.279966404059;6030.577859549965;6051.818445446644;6112.694365281807;5822.301323841166;5878.441548052928;5878.794242201502;5883.823485295448;5910.52715782745;5808.099744539603;5827.59206855516;5830.708464576669;5846.795362162304;5857.297124345106;5599.972000139973;5604;5640.379558248507;5655.519280861173;5716.085426331767;5789.507891829242;5825.679587622722;5843.883122337615;5850.970745146247;5891.587588868738;5628.915566266591;5713.771449141987;5717.42825717425;5722.914156287742;5740.282464691913 11:25:05 INFO - PROCESS | 2009 | |1;ext.html;459.78160373822436;477.286456747163;505.9595232381408;515.1062905858337;559.203135531867;30535;30754;40826.59173408265;41302;41363.58636413635;42694.7865260674;43106.35340469895;43130;43160.784196079054;43190.7840460798;9723.319367644268;9912.504374781269;9921.851172232422;9936.105750482455;9944;8803.33974951878;9013.098690131;9038.638454461814;9045;11943.283402995805;11906.761864762699;12280.877191228063;12297.815532767016;12301.753964920696;12303.569375071875;10042.54808533614;12170.330631815246;12184.57353992606;12187.512499499964;12193.573224937174;11877.881221187765;11956.402179891058;11966.880331196751;11982.64052078439;11992.820107698391;40107.197856042716;40369;40415.595844041774;40554.594454055376;40569.797151014056;3288.9506657400157;3339.465685490334;3365.9495107573407;3379.4086034943784;3487.4071407860724;30696.53955190674;31204.843975779975;31592.36815263682;31896.20259493508;32520.837395813105;3209.582754241934;3304.438245498247;3363.83180840957;3404.914877128067;3414.0440676610588;463996.6800166011;467739;468119;468638.6568067172;469146;57648.42351576514;58136.8372632545;58143.418565814645;58210.8357832841;58276;64382;64465;64616.67691661511;64643.03035454378;64703.67648161729;16416.75374869378;16644.750328745078;16663;16676.83323166777;16698.58253543671;12796.296203708886;12865.099443038898;12865.48538058486;12869.292188929512;12878.871211287955;16017.58848126905;16463.83536164623;16902.492925212264;18632.813671863107;19055.80944190568;6580.736770529125;7497.400207983457;7667.00328957232;7703.653335599912;7759.72840950564;875.671623141322;940.8682784410188;953.080277532171;1009.3388830316155;1038.807820553185;28375.006874759285;29519.55720664192;29729.70288177859;30076.947306844155;30413.847930760647;1016.5273147986074;1075.1452595186845;1099.6700989703056;1145.410113791398;1149.4367759797806;15256.160911149993;15390.230488475532;15421.460248891235;15491.99302045386;15886.205689715467;522831;585612;587943;588465.6343901041;589107;49610.75194624004;50123.24815127776;50179;52708;55246.723766380914;1358.1918758338772;1373.3888419653158;1394.8186735724291;1395.5324966136254;1398.4755716606273;25900.8704956474;29676.446654385414;30818.383632327677;31675;31799.205019874687;49047.264291034946;49496;49535;49539.752301238266;49581.752091239316;486274;489256;541069;541315.2934235461;641442;38013.61986380101;38477;39235.2152956945;40193.39709904412;40615.1876962465;44694.43569475053;44764;45500.77249613731;45589.316160258284;45809.77095114503;35465.468017979234;36402.4539631911;36863;37254.44118338173;38444;37033.259334813694;37967;38033.619663804115;38238.80880595579;38580.807095965465;37853.62146378611;38595.80702096471;38807.61192388153;38854;38884;29517.11448656544;30505.694943050286;32363;32907.848225312;33244.1688957778;24844.37889052788;25038.74961250364;25268.747312527375;25431.60756950239;26002.609960850223 11:25:05 INFO - PROCESS | 2009 | |2;jquery.html;347.66103049526714;348.8691740597276;360.778121455305;369.26884768159044;371.75835706790576;15348.923255383721;16253.83746162538;20191.293304734336;20265.797342026577;20920;14659.633509162277;18892.622147557042;18931.52671183221;19101.23595056196;19876.20495180195;4712.646551508634;5506.889862202754;5508.283923089993;5511.200875873016;5518.889622207574;5164.896702065957;5224.45143259958;6031.788887388942;6074.088886667013;6079.331273559905;5771.68128326212;6031.909521357183;6043.8186854394435;6043.939560604404;6049.546284028694;5822.767089316422;5954.910676339859;5959.582829201958;5960.046392577166;5962.34414214436;4470.240059189946;5023.547880690719;5250.05499010178;5253.658512196695;5268.736563171864;24152;24790.5041899161;24816.751832481623;25099.37251568707;25159.24522264335;2151.440625437382;2188.715466989281;2213.8007579317778;2315.120254303354;2326.8487548309304;9456.52717364129;9974.002599739968;10139.036791504795;10319.019693129141;10411.947940260327;1694.3561446650317;1734.9826501734947;1759.3314540474537;1768.3103589600066;1823.9088045597666;122027.16959245618;122531.3873430636;122950;124370;125708;9218.953905230564;11106.722331941604;11415.029722473517;11508.769824603462;11652.70868228284;14491.20298383578;14511.492097776738;14550.927245363706;14551.126932383879;14565.92717036408;8109.351251899834;8417.158284171534;8423.70517031901;8486.830263394697;8528.360372972053;6713;8270.958645206736;8277.04813946392;8440.493570385797;8474.406791524658;4471.821127154877;4485.430099465161;4486.641068714495;4507.5943165115295;4548.499665036843;1583.3112596020767;1792.6059863707283;1841.5948491331885;1861.6462872054267;1917.1625993529653;781.5115552779513;795.0419744208215;797.2266901105918;852.7740148860557;979.5787811241235;19871;20139.39581812548;20940.89529552373;21029;21078.262260821106;788.8304014636881;899.964001439935;903.2412773270485;928.6656803550717;1041.9270651054505;8890.466572005571;10557.736056598493;10937.671869843918;11002.449877506253;11097.889021109848;576254;588854;588986;589518.0524097438;596459;125913;127612.72387276008;128218.99999999814;136468.9529657056;136594;957.7797106665506;967.8644989701447;1014.4826138669332;1014.822406078948;1016.8576399304103;14023.929880350533;15367.615809604402;17638;17900.104994750473;20894.47763805917;58423;58575.121373180285;58836.82326353363;59178;60600;78160.82758758737;80357;82058;84507.5774621123;85637;6937.410320122846;7213.6032518212005;7249.855002900018;7513.549187048795;7913.604319783873;11396.829047564459;12724.363781811058;12764.61706148817;13112.278824664734;13118.737625247251;11072.723181920517;11112.555497780322;11250.156238281998;11351.829722554003;11446.37044962535;10643.840342394715;11069.391183484664;11269;11329.830052549054;11491.965723084963;10090.192784577363;10388.896111038794;10541.472926353805;11297.71755706114;11947.940260298643;7221.386182174574;7273.854522909618;7378.778636640909;7449.292317229854;7590.316871481593;9130.589123489592;10185.624940632919;10472.842907356548;11111.944365285284;11520.481578329163 11:25:05 INFO - PROCESS | 2009 | |3;mootools.html;1487.464512775401;1520.7718842173672;1713.1947984447313;1880.5110671225477;1906.8665193436457;8261.173882611743;8797.42816716913;8804.471731696094;8804.8679269811;8809.823803523926;7586.431017673669;7804.648790804413;8281.54451505167;8506.319494440446;8603;2298.5977453945534;2333.614953532665;2334.9182778602753;2339.94150146246;2342.2856028911174;2301.666258392532;2323.0707716913203;2343.625019996801;2344.42561572415;2353.8116950643907;2321.7446080931086;2333.9649905251435;2342.278425357361;2350.3888988862846;2351.2828587280874;2279.908803647852;2299.5975704251773;2307.3424074138875;2308.8845557722047;2314.201600447843;2235.7205349331334;2241.0923575951765;2242.215224671352;2242.5963326601204;2249.3701763506247;8603.612837422268;8626.309895208431;8695.913040869573;8704;8746.86879696805;5114.872128196787;5263.526282634582;5264.131418315973;5266.1574148136115;5273.736313184326;3416.4875268709893;3461.6192218855904;3543.20277937462;3591.28174365128;3597.5503062117236;1093.4587379247323;1110.6723516562633;1768.8584913206912;1830.7162389829464;1870.7661542307212;11171.497282622302;11226.494807733672;11257;11257.043151332147;11260.930211629964;3850.8652197172964;4153.279835821401;4465.151621191963;4495.865124046219;4511.954880451219;8875.511846848356;8881.42270752399;8887.777805554913;8892.3330750194;8916.509591972374;5111.437741848394;5143.871403214949;5146.716930568779;5148.330717006766;5148.4851514848215;3672.7245456590326;4424.292113261927;4782.856514304576;4837.201861692782;4840.6127509799135;3597.111516453883;3638.9090272742865;3706.8887933362043;3742.0831896185064;3751.4747935289083;1122.1135303110614;1129.4014172488594;1130.4912789244709;1138.698244965085;1138.9578535639796;801.8276070644839;826.6362800367818;875.2472873328932;898.9235914947303;914.5015966298383;5543.722813859372;5587.664740115606;5832.591718579659;5835.174239258052;6009.849753756103;375.8459031797005;436.85802114312975;444.0652426641937;445.35423635727676;447.74702293204234;3871.109644781716;3879.573246942834;3951.8024098794945;3997.760134391946;4037.4953130858644;9864.816222053256;9929.30494865367;10285.897141028643;10595.576176952989;10635.680929572125;10478.266521343585;10510.842337364946;10511.001454861776;10511.579536818595;10520.473976301155;7756.844863102707;7841.019872515935;7936.761897143095;8065.354771618257;8068.636911339004;5754.309482862083;5817.055930549795;5906.438888305604;5962.791302304398;6078.3921607838865;8301.294389976678;9851.310408271353;9891.35706179096;9891.406515609087;9904.851427228454;9399;9851.45816980073;9876.40741555509;10040.146587539848;10072.496375181358;7346.595937223504;7369.8157546061775;7380.889286660812;7403.259674032769;7446.032015837906;7974.202579741979;8032.076311224284;8051;8052.154523775153;8087.3125784308995;6136.723847426966;7163.892541611775;7259.709611615476;7330.157031941182;7424.1462231844025;7186.496945213785;7215.953686715292;7237.421006319377;7275.417966562556;7299.52553084069;6808.319168083152;6871.759488417882;6911.930880691129;6913.792586222421;6930.584164949917;4925.581325587365;4959.677620954626;4998.375203099613;5013.623978201576;5077.974610126926;4906.423874557319;4934.407871055353;5009.348784657971;5153.922691159561;5155.9742201290255 11:25:05 INFO - PROCESS | 2009 | |4;prototype.html;292.47792690048254;299.5626385477203;307.8845432962639;312.2988889942079;315.46686100490183;4744.478107408187;5077.851731926211;5230.450802665713;5269.262303277545;5299.8940021199605;4775.6418268629905;4779.617630589553;5228.849653076317;5239.192478594879;5267.947320526793;3885.941710874339;4045.4538637283886;4050;4054.0067683417606;4062.4109504121884;3504.983554769114;3560.376934036546;3830.578636349999;3850.249201405727;4039.474868267121;3495.3009398120353;3709.684676802475;3948.072203032285;3959.3269144245405;4030.2141082488924;3704.3887758519813;3724.1434470071763;3984.740991835536;3989.5412027616903;4019.4573732546023;3501.299740052;3510.7542472026967;3513.2095278562374;3519.964800351989;3525.6121826599056;4509.684322097454;4689;4998;4999.600031997432;5039.319691841628;4530.29780384041;4557.931631025537;4558.8632341029825;4569.817207311704;4574.954250457519;4545.477270113946;4645.188376533155;4968.30443737877;5331.786728530854;5521.861953451196;3318.1206980150278;3468.8899552143325;3599.6220396858216;3768.2463507298376;3788.9621103788886;4217.641500472433;5569.248151499536;5629.774809007676;5645.237892884449;5778.768849246025;3732.432378401078;3821.464994900716;3827.196288779332;3832.5784163741964;3833.3291673956946;3228.322052369029;3869.3422118239405;3893.3575959966856;3893.377059670439;3904.902377440587;3761.7774223376964;4490.595846373842;4503.279475284005;4506.580427165432;4515.887102822456;4842.225243960949;5419.566434685215;5543.2793736814765;5566.2207290979295;5592.853465039657;1141.007323628432;1322.332222227788;1322.4908410261926;1323.2259128409796;1328.7509740843577;676.1548064918851;720.7926722739495;733.9944276341446;736.9535259930911;781.394234847394;3755.4179102239195;4328.415663885336;4395.84603544261;4404.11917616478;4411.470623525198;3939.4484772131923;3963.6630886374983;4257.723247988871;4761.833380801797;4779.354787103696;3832.850144956502;3983.2515564260566;4011.3782363733667;4313.956860431418;4359.869203923824;2138.3371154941706;2232.8596063507075;2233.6761169630313;2488.9128880489097;2491.798039519209;4624;4645.907081858344;4795.184818580849;4799.088173247142;4817.590504807061;4138.082651730523;4637.240841699184;4736.8578942631775;4752.1921273383605;4823;3670.192048416583;4314.697971142053;4820.4456487503685;4840.782164802522;4928.507149285043;4601.67788254819;4608.723476591415;4625.953740462553;4645.674802763774;4672.602828759457;3703.8888833335045;4514.68397212192;4554.795034223402;4591.334256532851;4706;4621.653375996746;4851.2105539314325;4880.853574392774;5534.335879694462;5918.467337939606;3524.5946716126946;3748.9128152835374;3838.136419305634;3850.4609354689806;3854.1520865409566;4493.438320209973;5017.774200161075;5134.05020071288;5768.307803063659;5769.711514424346;5136.635503745053;5399.757010934439;5565.805196818092;5601.207613563621;5662.433756624305;5520.585956053393;5524.66851988882;5533.723313834212;5554.361248456479;5599.496045355774;4604.332371806003;5018.573421259234;5155.690658560498;5456.972715136399;5473.945260547503;4244.639205667553;4997.525235102659;5528.308961379827;5568.192612071308;5604.607677462538;4340.8697739067875;5480.835574932758;5487.725613719378;5494.615376923577;5499.202615620633 11:25:05 INFO - PROCESS | 2009 | |5;yui.html;767.9462437629364;776.8365103187624;784.5528049012062;793.773774474275;798.7884546858298;14994.250287485624;15816.44642437515;15876.28556714949;15893.2848021839;16222.107784071872;14302.856971430283;14642.780358294634;14668.779968300483;14677.119372837637;14678.926605366985;669.725412580842;820.1183727492954;845.6701886264361;847.1020718038868;848.9405741598091;684.9520533562653;827.2554700769296;834.3033566971586;847.9448835825682;850.5407080176698;880.454118446564;882.5322579033123;882.7219425880859;884.0452311503593;884.7876509637705;702.8980797784344;877.8770972063916;878.376352789517;878.9340799440067;882.6689991253312;639.3702203329716;652.1359199061263;660.21764209412;660.8281846719888;660.9504287178481;14434.927825360912;14556.344964476624;14563.70872582553;14563.92718036403;14600.342984565614;13219.735605287937;13583.320833958263;13715.725685486233;13772.898168146525;13999.790003149961;12301.077419193507;12446.190997585218;12493.25040497573;12511.874881251162;12948.417321220473;4435.290353543418;4467.262901621229;4492.640588752892;4566.566176213254;4591.058832939239;107134;110318.44840775825;110544.4472777639;110560;110819;10141.949290253502;10745.355278683304;10747.355158690347;10846.674599761862;11088.168387370979;19998.800011999694;20710.378688639365;20918.267860624805;20921.84929828874;20991;7528.209537998431;7589.051368578927;7672.232776722283;7723.691052357843;7773.533587984739;4284.91430171401;4326.784238356491;4337.544557821383;4360.258756011485;4480.619147372445;3385.6445073267446;3510;3511.3152935177395;3516.6307537708685;3517.6457064030533;636.9363063693594;640.484410049905;652.6019128331714;657.4050484311706;658.8221180281297;563.2233090862089;578.5805291163956;612.2744547710888;617.9598326108788;624.9125122482856;8823.955880220687;8838.337124715672;8840.602172902234;9036.502992335354;9057.320700947455;577.6863661037518;581.4882903045293;608.9086637004397;612.886615976037;614.2536817766435;4339.349097635317;4376.803043863034;4401.383806267125;4434.11317736454;4467.396901418332;152680;156655;157370;157790.42209577988;162338;132715.33642331918;134811;135654;140400;143432;95309.0469095314;116102;116809;118736;120283;8181.263686268265;8421.578921054046;8497.2777313929;8747.256483198998;8790.648374064966;48901.5109848897;57704.42295576991;64065;65868.69459568892;66710;74900;85671;94954;98808.01191988276;100478.99521004697;10244.129249013618;10408.531616077144;10520.316179448311;10536.209784266057;11153.163512736415;11939.164258501822;11966.401679915794;12059.75880482368;12216.96155826729;12444.875551244371;11283.943580282374;11476.655700329004;11642.825357619811;11865.881341186478;11908.345041022827;10142.188624910137;10866.345339350264;11140.944295278472;11470.770584588428;12018.819717704417;11258.605948791754;11268.7746245073;11317.603883864025;11621.593244236412;11724.765504690029;8898.976617689048;9159.587818548049;9261.629534818532;9499.905000950179;9815.705528834147;9471.47906865129;10243.821960474343;10752.462376881282;10910.945445272722;11256.380899050631 11:25:05 INFO - PROCESS | 2009 | __end_tp_report 11:25:05 INFO - PROCESS | 2009 | __start_cc_report 11:25:05 INFO - PROCESS | 2009 | _x_x_mozilla_cycle_collect,1183 11:25:05 INFO - PROCESS | 2009 | __end_cc_report 11:25:05 INFO - PROCESS | 2009 | __startTimestamp1472927105844__endTimestamp 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | ------- Summary: start ------- 11:25:05 INFO - PROCESS | 2009 | Number of tests: 6 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | [#0] dojo.html Cycles:165 Average:5540.19 Median:5716.09 stddev:637.61 (11.2%) stddev-sans-first:613.01 11:25:05 INFO - PROCESS | 2009 | Values: 3219.1 3229.4 3257.5 3268.5 3318.4 5375.8 5401.6 5467.6 5474.3 5499.6 4491.7 5026.1 5456.7 5458.1 5460.2 4118.6 4971.7 5021.2 5051.1 5103.5 5007.5 5107.7 5108.0 5108.4 5110.0 4999.5 5043.7 5061.2 5065.1 5073.4 5085.6 5091.3 5103.9 5107.3 5124.6 4901.3 4938.9 4950.9 5029.8 5049.1 5447.6 5473.6 5495.1 5496.2 5534.9 5969.1 5974.6 5993.5 5996.7 6004.6 5762.7 6035.3 6049.4 6052.2 6082.1 5576.8 5589.8 5596.3 5602.6 5649.2 6196.6 6255.8 6262.4 6272.0 6273.5 5749.3 5802.7 5821.3 5903.0 5942.6 6014.9 6029.2 6058.5 6067.2 6088.9 5894.4 5969.3 5981.2 5983.9 5984.5 4981.9 5737.7 5784.0 5810.4 5999.5 4723.4 4752.8 4758.0 4761.5 4770.5 4284.7 4307.9 4313.0 4344.5 4368.3 5540.4 5540.9 5548.4 5552.7 5585.1 5949.7 6073.9 6101.6 6102.6 6152.0 5503.0 5510.2 5564.7 5564.7 5616.6 5251.2 5265.8 5265.9 5275.2 5280.7 6300.7 6303.2 6314.6 6330.8 6336.8 5879.7 5940.1 6035.1 6075.5 6084.6 6329.7 6332.9 6334.0 6354.9 6360.2 5906.9 5925.7 5933.3 5943.6 5944.6 5869.0 6000.3 6030.6 6051.8 6112.7 5822.3 5878.4 5878.8 5883.8 5910.5 5808.1 5827.6 5830.7 5846.8 5857.3 5600.0 5604.0 5640.4 5655.5 5716.1 5789.5 5825.7 5843.9 5851.0 5891.6 5628.9 5713.8 5717.4 5722.9 5740.3 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | [#1] ext.html Cycles:180 Average:66899.15 Median:37910.31 stddev:141290.78 (372.7%) stddev-sans-first:141599.08 11:25:05 INFO - PROCESS | 2009 | Values: 459.8 477.3 506.0 515.1 559.2 30535.0 30754.0 40826.6 41302.0 41363.6 42694.8 43106.4 43130.0 43160.8 43190.8 9723.3 9912.5 9921.9 9936.1 9944.0 8803.3 9013.1 9038.6 9045.0 11943.3 11906.8 12280.9 12297.8 12301.8 12303.6 10042.5 12170.3 12184.6 12187.5 12193.6 11877.9 11956.4 11966.9 11982.6 11992.8 40107.2 40369.0 40415.6 40554.6 40569.8 3289.0 3339.5 3365.9 3379.4 3487.4 30696.5 31204.8 31592.4 31896.2 32520.8 3209.6 3304.4 3363.8 3404.9 3414.0 463996.7 467739.0 468119.0 468638.7 469146.0 57648.4 58136.8 58143.4 58210.8 58276.0 64382.0 64465.0 64616.7 64643.0 64703.7 16416.8 16644.8 16663.0 16676.8 16698.6 12796.3 12865.1 12865.5 12869.3 12878.9 16017.6 16463.8 16902.5 18632.8 19055.8 6580.7 7497.4 7667.0 7703.7 7759.7 875.7 940.9 953.1 1009.3 1038.8 28375.0 29519.6 29729.7 30076.9 30413.8 1016.5 1075.1 1099.7 1145.4 1149.4 15256.2 15390.2 15421.5 15492.0 15886.2 522831.0 585612.0 587943.0 588465.6 589107.0 49610.8 50123.2 50179.0 52708.0 55246.7 1358.2 1373.4 1394.8 1395.5 1398.5 25900.9 29676.4 30818.4 31675.0 31799.2 49047.3 49496.0 49535.0 49539.8 49581.8 486274.0 489256.0 541069.0 541315.3 641442.0 38013.6 38477.0 39235.2 40193.4 40615.2 44694.4 44764.0 45500.8 45589.3 45809.8 35465.5 36402.5 36863.0 37254.4 38444.0 37033.3 37967.0 38033.6 38238.8 38580.8 37853.6 38595.8 38807.6 38854.0 38884.0 29517.1 30505.7 32363.0 32907.8 33244.2 24844.4 25038.7 25268.7 25431.6 26002.6 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | [#2] jquery.html Cycles:180 Average:34952.42 Median:24803.63 stddev:98615.58 (397.6%) stddev-sans-first:98857.99 11:25:05 INFO - PROCESS | 2009 | Values: 347.7 348.9 360.8 369.3 371.8 15348.9 16253.8 20191.3 20265.8 20920.0 14659.6 18892.6 18931.5 19101.2 19876.2 4712.6 5506.9 5508.3 5511.2 5518.9 5164.9 5224.5 6031.8 6074.1 6079.3 5771.7 6031.9 6043.8 6043.9 6049.5 5822.8 5954.9 5959.6 5960.0 5962.3 4470.2 5023.5 5250.1 5253.7 5268.7 24152.0 24790.5 24816.8 25099.4 25159.2 2151.4 2188.7 2213.8 2315.1 2326.8 9456.5 9974.0 10139.0 10319.0 10411.9 1694.4 1735.0 1759.3 1768.3 1823.9 122027.2 122531.4 122950.0 124370.0 125708.0 9219.0 11106.7 11415.0 11508.8 11652.7 14491.2 14511.5 14550.9 14551.1 14565.9 8109.4 8417.2 8423.7 8486.8 8528.4 6713.0 8271.0 8277.0 8440.5 8474.4 4471.8 4485.4 4486.6 4507.6 4548.5 1583.3 1792.6 1841.6 1861.6 1917.2 781.5 795.0 797.2 852.8 979.6 19871.0 20139.4 20940.9 21029.0 21078.3 788.8 900.0 903.2 928.7 1041.9 8890.5 10557.7 10937.7 11002.4 11097.9 576254.0 588854.0 588986.0 589518.1 596459.0 125913.0 127612.7 128219.0 136469.0 136594.0 957.8 967.9 1014.5 1014.8 1016.9 14023.9 15367.6 17638.0 17900.1 20894.5 58423.0 58575.1 58836.8 59178.0 60600.0 78160.8 80357.0 82058.0 84507.6 85637.0 6937.4 7213.6 7249.9 7513.5 7913.6 11396.8 12724.4 12764.6 13112.3 13118.7 11072.7 11112.6 11250.2 11351.8 11446.4 10643.8 11069.4 11269.0 11329.8 11492.0 10090.2 10388.9 10541.5 11297.7 11947.9 7221.4 7273.9 7378.8 7449.3 7590.3 9130.6 10185.6 10472.8 11111.9 11520.5 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | [#3] mootools.html Cycles:180 Average:5545.41 Median:5011.49 stddev:3081.73 (61.5%) stddev-sans-first:3075.28 11:25:05 INFO - PROCESS | 2009 | Values: 1487.5 1520.8 1713.2 1880.5 1906.9 8261.2 8797.4 8804.5 8804.9 8809.8 7586.4 7804.6 8281.5 8506.3 8603.0 2298.6 2333.6 2334.9 2339.9 2342.3 2301.7 2323.1 2343.6 2344.4 2353.8 2321.7 2334.0 2342.3 2350.4 2351.3 2279.9 2299.6 2307.3 2308.9 2314.2 2235.7 2241.1 2242.2 2242.6 2249.4 8603.6 8626.3 8695.9 8704.0 8746.9 5114.9 5263.5 5264.1 5266.2 5273.7 3416.5 3461.6 3543.2 3591.3 3597.6 1093.5 1110.7 1768.9 1830.7 1870.8 11171.5 11226.5 11257.0 11257.0 11260.9 3850.9 4153.3 4465.2 4495.9 4512.0 8875.5 8881.4 8887.8 8892.3 8916.5 5111.4 5143.9 5146.7 5148.3 5148.5 3672.7 4424.3 4782.9 4837.2 4840.6 3597.1 3638.9 3706.9 3742.1 3751.5 1122.1 1129.4 1130.5 1138.7 1139.0 801.8 826.6 875.2 898.9 914.5 5543.7 5587.7 5832.6 5835.2 6009.8 375.8 436.9 444.1 445.4 447.7 3871.1 3879.6 3951.8 3997.8 4037.5 9864.8 9929.3 10285.9 10595.6 10635.7 10478.3 10510.8 10511.0 10511.6 10520.5 7756.8 7841.0 7936.8 8065.4 8068.6 5754.3 5817.1 5906.4 5962.8 6078.4 8301.3 9851.3 9891.4 9891.4 9904.9 9399.0 9851.5 9876.4 10040.1 10072.5 7346.6 7369.8 7380.9 7403.3 7446.0 7974.2 8032.1 8051.0 8052.2 8087.3 6136.7 7163.9 7259.7 7330.2 7424.1 7186.5 7216.0 7237.4 7275.4 7299.5 6808.3 6871.8 6911.9 6913.8 6930.6 4925.6 4959.7 4998.4 5013.6 5078.0 4906.4 4934.4 5009.3 5153.9 5156.0 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | [#4] prototype.html Cycles:180 Average:4191.47 Median:4556.36 stddev:1280.71 (28.1%) stddev-sans-first:1250.42 11:25:05 INFO - PROCESS | 2009 | Values: 292.5 299.6 307.9 312.3 315.5 4744.5 5077.9 5230.5 5269.3 5299.9 4775.6 4779.6 5228.8 5239.2 5267.9 3885.9 4045.5 4050.0 4054.0 4062.4 3505.0 3560.4 3830.6 3850.2 4039.5 3495.3 3709.7 3948.1 3959.3 4030.2 3704.4 3724.1 3984.7 3989.5 4019.5 3501.3 3510.8 3513.2 3520.0 3525.6 4509.7 4689.0 4998.0 4999.6 5039.3 4530.3 4557.9 4558.9 4569.8 4575.0 4545.5 4645.2 4968.3 5331.8 5521.9 3318.1 3468.9 3599.6 3768.2 3789.0 4217.6 5569.2 5629.8 5645.2 5778.8 3732.4 3821.5 3827.2 3832.6 3833.3 3228.3 3869.3 3893.4 3893.4 3904.9 3761.8 4490.6 4503.3 4506.6 4515.9 4842.2 5419.6 5543.3 5566.2 5592.9 1141.0 1322.3 1322.5 1323.2 1328.8 676.2 720.8 734.0 737.0 781.4 3755.4 4328.4 4395.8 4404.1 4411.5 3939.4 3963.7 4257.7 4761.8 4779.4 3832.9 3983.3 4011.4 4314.0 4359.9 2138.3 2232.9 2233.7 2488.9 2491.8 4624.0 4645.9 4795.2 4799.1 4817.6 4138.1 4637.2 4736.9 4752.2 4823.0 3670.2 4314.7 4820.4 4840.8 4928.5 4601.7 4608.7 4626.0 4645.7 4672.6 3703.9 4514.7 4554.8 4591.3 4706.0 4621.7 4851.2 4880.9 5534.3 5918.5 3524.6 3748.9 3838.1 3850.5 3854.2 4493.4 5017.8 5134.1 5768.3 5769.7 5136.6 5399.8 5565.8 5601.2 5662.4 5520.6 5524.7 5533.7 5554.4 5599.5 4604.3 5018.6 5155.7 5457.0 5473.9 4244.6 4997.5 5528.3 5568.2 5604.6 4340.9 5480.8 5487.7 5494.6 5499.2 11:25:05 INFO - PROCESS | 2009 | 11:25:05 INFO - PROCESS | 2009 | [#5] yui.html Cycles:180 Average:24929.75 Median:4332.16 stddev:41314.81 (953.7%) stddev-sans-first:41390.87 11:25:05 INFO - PROCESS | 2009 | Values: 767.9 776.8 784.6 793.8 798.8 14994.3 15816.4 15876.3 15893.3 16222.1 14302.9 14642.8 14668.8 14677.1 14678.9 669.7 820.1 845.7 847.1 848.9 685.0 827.3 834.3 847.9 850.5 880.5 882.5 882.7 884.0 884.8 702.9 877.9 878.4 878.9 882.7 639.4 652.1 660.2 660.8 661.0 14434.9 14556.3 14563.7 14563.9 14600.3 13219.7 13583.3 13715.7 13772.9 13999.8 12301.1 12446.2 12493.3 12511.9 12948.4 4435.3 4467.3 4492.6 4566.6 4591.1 107134.0 110318.4 110544.4 110560.0 110819.0 10141.9 10745.4 10747.4 10846.7 11088.2 19998.8 20710.4 20918.3 20921.8 20991.0 7528.2 7589.1 7672.2 7723.7 7773.5 4284.9 4326.8 4337.5 4360.3 4480.6 3385.6 3510.0 3511.3 3516.6 3517.6 636.9 640.5 652.6 657.4 658.8 563.2 578.6 612.3 618.0 624.9 8824.0 8838.3 8840.6 9036.5 9057.3 577.7 581.5 608.9 612.9 614.3 4339.3 4376.8 4401.4 4434.1 4467.4 152680.0 156655.0 157370.0 157790.4 162338.0 132715.3 134811.0 135654.0 140400.0 143432.0 95309.0 116102.0 116809.0 118736.0 120283.0 8181.3 8421.6 8497.3 8747.3 8790.6 48901.5 57704.4 64065.0 65868.7 66710.0 74900.0 85671.0 94954.0 98808.0 100479.0 10244.1 10408.5 10520.3 10536.2 11153.2 11939.2 11966.4 12059.8 12217.0 12444.9 11283.9 11476.7 11642.8 11865.9 11908.3 10142.2 10866.3 11140.9 11470.8 12018.8 11258.6 11268.8 11317.6 11621.6 11724.8 8899.0 9159.6 9261.6 9499.9 9815.7 9471.5 10243.8 10752.5 10910.9 11256.4 11:25:05 INFO - PROCESS | 2009 | -------- Summary: end -------- 11:25:05 INFO - PROCESS | 2009 | 11:25:06 INFO - TEST-INFO | 2009: exit 0 11:25:06 INFO - TEST-OK | dromaeo_css | took 1117207ms 11:25:06 INFO - TEST-START | kraken 11:25:06 INFO - operating with platform_type : mac_ 11:25:06 INFO - Initialising browser for kraken test... 11:25:06 INFO - TEST-INFO | started process 2012 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpa5tMBp/profile http://localhost:49705/getInfo.html) 11:25:08 INFO - PROCESS | 2012 | __metrics Screen width/height:1600/1200 11:25:08 INFO - PROCESS | 2012 | colorDepth:24 11:25:08 INFO - PROCESS | 2012 | Browser inner width/height: 1024/689 11:25:08 INFO - PROCESS | 2012 | __metrics 11:25:08 INFO - PROCESS | 2012 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:25:08 INFO - PROCESS | 2012 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:25:09 INFO - TEST-INFO | 2012: exit 0 11:25:09 INFO - Browser initialized. 11:25:09 INFO - Running cycle 1/1 for kraken test... 11:25:09 INFO - TEST-INFO | started process 2013 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/T/tmpa5tMBp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:25:21 INFO - PROCESS | 2013 | RSS: Main: 233861120 11:25:21 INFO - PROCESS | 2013 | 11:25:24 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-beat-detection) 11:25:25 INFO - PROCESS | 2013 | RSS: Main: 293015552 11:25:25 INFO - PROCESS | 2013 | 11:25:27 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-dft) 11:25:28 INFO - PROCESS | 2013 | RSS: Main: 288890880 11:25:28 INFO - PROCESS | 2013 | 11:25:31 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-fft) 11:25:32 INFO - PROCESS | 2013 | RSS: Main: 379396096 11:25:32 INFO - PROCESS | 2013 | 11:25:34 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49705/tests/kraken/driver.html?testName=audio-oscillator) 11:25:35 INFO - PROCESS | 2013 | RSS: Main: 281718784 11:25:35 INFO - PROCESS | 2013 | 11:25:37 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49705/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:25:37 INFO - PROCESS | 2013 | RSS: Main: 285274112 11:25:37 INFO - PROCESS | 2013 | 11:25:40 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49705/tests/kraken/driver.html?testName=imaging-darkroom) 11:25:41 INFO - PROCESS | 2013 | RSS: Main: 322527232 11:25:41 INFO - PROCESS | 2013 | 11:25:44 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49705/tests/kraken/driver.html?testName=imaging-desaturate) 11:25:44 INFO - PROCESS | 2013 | RSS: Main: 325070848 11:25:44 INFO - PROCESS | 2013 | 11:25:47 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49705/tests/kraken/driver.html?testName=json-parse-financial) 11:25:48 INFO - PROCESS | 2013 | RSS: Main: 324702208 11:25:48 INFO - PROCESS | 2013 | 11:25:49 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49705/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:25:50 INFO - PROCESS | 2013 | RSS: Main: 297107456 11:25:50 INFO - PROCESS | 2013 | 11:25:52 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:25:52 INFO - PROCESS | 2013 | RSS: Main: 303239168 11:25:52 INFO - PROCESS | 2013 | 11:25:55 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:25:55 INFO - PROCESS | 2013 | RSS: Main: 307892224 11:25:55 INFO - PROCESS | 2013 | 11:25:58 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:25:59 INFO - PROCESS | 2013 | RSS: Main: 312864768 11:25:59 INFO - PROCESS | 2013 | 11:26:02 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:26:02 INFO - PROCESS | 2013 | RSS: Main: 314200064 11:26:02 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49705/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49705/tests/kraken/driver.html?testName=ai-astar) 11:26:04 INFO - PROCESS | 2013 | RSS: Main: 357613568 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | __start_tp_report 11:26:04 INFO - PROCESS | 2013 | _x_x_mozilla_page_load 11:26:04 INFO - PROCESS | 2013 | _x_x_mozilla_page_load_details 11:26:04 INFO - PROCESS | 2013 | |i|pagename|runs| 11:26:04 INFO - PROCESS | 2013 | |0;ai-astar;113;115;113;168;136;113;113;114;115;124 11:26:04 INFO - PROCESS | 2013 | |1;audio-beat-detection;148;113;129;163;107;130;131;107;160;107 11:26:04 INFO - PROCESS | 2013 | |2;audio-dft;187;184;185;184;185;184;180;185;182;185 11:26:04 INFO - PROCESS | 2013 | |3;audio-fft;79;75;76;159;61;78;80;87;147;58 11:26:04 INFO - PROCESS | 2013 | |4;audio-oscillator;82;84;135;69;74;80;120;69;69;116 11:26:04 INFO - PROCESS | 2013 | |5;imaging-gaussian-blur;90;87;90;88;90;85;91;88;90;88 11:26:04 INFO - PROCESS | 2013 | |6;imaging-darkroom;94;96;96;109;149;88;145;149;97;94 11:26:04 INFO - PROCESS | 2013 | |7;imaging-desaturate;86;90;138;86;84;85;86;87;84;84 11:26:04 INFO - PROCESS | 2013 | |8;json-parse-financial;54;53;54;53;53;55;53;54;54;53 11:26:04 INFO - PROCESS | 2013 | |9;json-stringify-tinderbox;66;66;77;79;73;71;72;71;72;73 11:26:04 INFO - PROCESS | 2013 | |10;stanford-crypto-aes;88;88;88;86;88;85;84;89;89;110 11:26:04 INFO - PROCESS | 2013 | |11;stanford-crypto-ccm;140;136;135;140;152;151;154;147;157;141 11:26:04 INFO - PROCESS | 2013 | |12;stanford-crypto-pbkdf2;170;170;170;167;170;170;169;170;166;171 11:26:04 INFO - PROCESS | 2013 | |13;stanford-crypto-sha256-iterative;67;67;70;85;84;84;85;67;67;67 11:26:04 INFO - PROCESS | 2013 | __end_tp_report 11:26:04 INFO - PROCESS | 2013 | __start_cc_report 11:26:04 INFO - PROCESS | 2013 | _x_x_mozilla_cycle_collect,1977 11:26:04 INFO - PROCESS | 2013 | __end_cc_report 11:26:04 INFO - PROCESS | 2013 | __startTimestamp1472927164896__endTimestamp 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | ------- Summary: start ------- 11:26:04 INFO - PROCESS | 2013 | Number of tests: 14 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#0] ai-astar Cycles:10 Average:122.40 Median:115.00 stddev:17.64 (15.3%) stddev-sans-first:18.38 11:26:04 INFO - PROCESS | 2013 | Values: 113.0 115.0 113.0 168.0 136.0 113.0 113.0 114.0 115.0 124.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:130.50 stddev:21.57 (16.5%) stddev-sans-first:21.82 11:26:04 INFO - PROCESS | 2013 | Values: 148.0 113.0 129.0 163.0 107.0 130.0 131.0 107.0 160.0 107.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:1.91 (1.0%) stddev-sans-first:1.72 11:26:04 INFO - PROCESS | 2013 | Values: 187.0 184.0 185.0 184.0 185.0 184.0 180.0 185.0 182.0 185.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#3] audio-fft Cycles:10 Average:90.00 Median:77.00 stddev:34.43 (44.7%) stddev-sans-first:36.29 11:26:04 INFO - PROCESS | 2013 | Values: 79.0 75.0 76.0 159.0 61.0 78.0 80.0 87.0 147.0 58.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:71.50 stddev:24.45 (34.2%) stddev-sans-first:25.77 11:26:04 INFO - PROCESS | 2013 | Values: 82.0 84.0 135.0 69.0 74.0 80.0 120.0 69.0 69.0 116.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:90.00 stddev:1.83 (2.0%) stddev-sans-first:1.88 11:26:04 INFO - PROCESS | 2013 | Values: 90.0 87.0 90.0 88.0 90.0 85.0 91.0 88.0 90.0 88.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#6] imaging-darkroom Cycles:10 Average:111.70 Median:94.00 stddev:25.38 (27.0%) stddev-sans-first:26.10 11:26:04 INFO - PROCESS | 2013 | Values: 94.0 96.0 96.0 109.0 149.0 88.0 145.0 149.0 97.0 94.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:86.00 stddev:16.61 (19.3%) stddev-sans-first:17.52 11:26:04 INFO - PROCESS | 2013 | Values: 86.0 90.0 138.0 86.0 84.0 85.0 86.0 87.0 84.0 84.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 11:26:04 INFO - PROCESS | 2013 | Values: 54.0 53.0 54.0 53.0 53.0 55.0 53.0 54.0 54.0 53.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.50 stddev:4.08 (5.6%) stddev-sans-first:3.71 11:26:04 INFO - PROCESS | 2013 | Values: 66.0 66.0 77.0 79.0 73.0 71.0 72.0 71.0 72.0 73.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:88.00 stddev:7.40 (8.4%) stddev-sans-first:7.83 11:26:04 INFO - PROCESS | 2013 | Values: 88.0 88.0 88.0 86.0 88.0 85.0 84.0 89.0 89.0 110.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:149.00 stddev:7.89 (5.3%) stddev-sans-first:8.13 11:26:04 INFO - PROCESS | 2013 | Values: 140.0 136.0 135.0 140.0 152.0 151.0 154.0 147.0 157.0 141.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:170.00 stddev:1.57 (0.9%) stddev-sans-first:1.64 11:26:04 INFO - PROCESS | 2013 | Values: 170.0 170.0 170.0 167.0 170.0 170.0 169.0 170.0 166.0 171.0 11:26:04 INFO - PROCESS | 2013 | 11:26:04 INFO - PROCESS | 2013 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:77.00 stddev:8.83 (11.5%) stddev-sans-first:8.96 11:26:04 INFO - PROCESS | 2013 | Values: 67.0 67.0 70.0 85.0 84.0 84.0 85.0 67.0 67.0 67.0 11:26:04 INFO - PROCESS | 2013 | -------- Summary: end -------- 11:26:04 INFO - PROCESS | 2013 | 11:26:05 INFO - TEST-INFO | 2013: exit 0 11:26:05 INFO - TEST-OK | kraken | took 59254ms 11:26:05 INFO - SUITE-END | took 1176s 11:26:06 INFO - Completed test suite (00:19:36) 11:26:06 INFO - javascript benchmark 11:26:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3219.050380137859, 3229.3864165808495, 3257.511373294007, 3268.526063720762, 3318.4026875162463, 5375.75809088591, 5401.594880383967, 5467.589930755199, 5474.343078830536, 5499.58753093518, 4491.719859839946, 5026.0701770172545, 5456.727163641812, 5458.0721277382845, 5460.153676180198, 4118.629323360897, 4971.676841005323, 5021.196608542634, 5051.06555287273, 5103.515166059218, 5007.549320561149, 5107.744612769366, 5108.0, 5108.361454818148, 5110.003549307887, 4999.500049995007, 5043.747812609373, 5061.240813877929, 5065.062963351773, 5073.441921388644, 5085.644004919657, 5091.338126043628, 5103.948960510422, 5107.3105130807235, 5124.615653825978, 4901.313816065754, 4938.851834444937, 4950.87622809429, 5029.823956161516, 5049.141645920203, 5447.5641948644015, 5473.644213126134, 5495.093309603911, 5496.1755736639425, 5534.944650553483, 5969.134475501028, 5974.581779275452, 5993.460588547008, 5996.670183139926, 6004.639721616717, 5762.711864406805, 6035.336113027563, 6049.425304596057, 6052.182955301066, 6082.118092876508, 5576.776928922879, 5589.80435684749, 5596.272484576979, 5602.635828671123, 5649.209110724501, 6196.597221180609, 6255.812325630239, 6262.436380725665, 6271.96864015677, 6273.498120150377, 5749.252597162342, 5802.738876750556, 5821.301443826768, 5903.0260007099205, 5942.554308426886, 6014.939850601438, 6029.155918171459, 6058.515318774487, 6067.24159480065, 6088.939110608926, 5894.351621321651, 5969.343372229051, 5981.162637230791, 5983.910241346383, 5984.461398474158, 4981.875453113701, 5737.713114344266, 5783.987802134694, 5810.360860305363, 5999.490043346277, 4723.385959825201, 4752.7623618818925, 4757.976210118927, 4761.4524329701835, 4770.451398089264, 4284.67864910133, 4307.913841723211, 4313.0, 4344.478662560513, 4368.301071828492, 5540.418256083134, 5540.944590554042, 5548.361938377057, 5552.666839989613, 5585.106382978704, 5949.732262048218, 6073.878522429615, 6101.633901965896, 6102.603330783485, 6151.98492248774, 5503.0, 5510.228568000483, 5564.666120032811, 5564.721763911788, 5616.5787565931905, 5251.212318152231, 5265.76304066318, 5265.894682106336, 5275.155975043975, 5280.709560974184, 6300.747970081329, 6303.24528452296, 6314.62112273265, 6330.8417289567205, 6336.778212762531, 5879.706014699163, 5940.108983652487, 6035.064564992434, 6075.483583895419, 6084.574079814371, 6329.651869147241, 6332.905006425, 6334.018227174611, 6354.872902541831, 6360.173177486897, 5906.881862362815, 5925.703714814328, 5933.317668468181, 5943.613665111754, 5944.601919538446, 5869.0, 6000.279966404059, 6030.577859549965, 6051.818445446644, 6112.694365281807, 5822.301323841166, 5878.441548052928, 5878.794242201502, 5883.823485295448, 5910.52715782745, 5808.099744539603, 5827.59206855516, 5830.708464576669, 5846.795362162304, 5857.297124345106, 5599.972000139973, 5604.0, 5640.379558248507, 5655.519280861173, 5716.085426331767, 5789.507891829242, 5825.679587622722, 5843.883122337615, 5850.970745146247, 5891.587588868738, 5628.915566266591, 5713.771449141987, 5717.42825717425, 5722.914156287742, 5740.282464691913], "value": 5498.924634638608, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.78160373822436, 477.286456747163, 505.9595232381408, 515.1062905858337, 559.203135531867, 30535.0, 30754.0, 40826.59173408265, 41302.0, 41363.58636413635, 42694.7865260674, 43106.35340469895, 43130.0, 43160.784196079054, 43190.7840460798, 9723.319367644268, 9912.504374781269, 9921.851172232422, 9936.105750482455, 9944.0, 8803.33974951878, 9013.098690131, 9038.638454461814, 9045.0, 11943.283402995805, 11906.761864762699, 12280.877191228063, 12297.815532767016, 12301.753964920696, 12303.569375071875, 10042.54808533614, 12170.330631815246, 12184.57353992606, 12187.512499499964, 12193.573224937174, 11877.881221187765, 11956.402179891058, 11966.880331196751, 11982.64052078439, 11992.820107698391, 40107.197856042716, 40369.0, 40415.595844041774, 40554.594454055376, 40569.797151014056, 3288.9506657400157, 3339.465685490334, 3365.9495107573407, 3379.4086034943784, 3487.4071407860724, 30696.53955190674, 31204.843975779975, 31592.36815263682, 31896.20259493508, 32520.837395813105, 3209.582754241934, 3304.438245498247, 3363.83180840957, 3404.914877128067, 3414.0440676610588, 463996.6800166011, 467739.0, 468119.0, 468638.6568067172, 469146.0, 57648.42351576514, 58136.8372632545, 58143.418565814645, 58210.8357832841, 58276.0, 64382.0, 64465.0, 64616.67691661511, 64643.03035454378, 64703.67648161729, 16416.75374869378, 16644.750328745078, 16663.0, 16676.83323166777, 16698.58253543671, 12796.296203708886, 12865.099443038898, 12865.48538058486, 12869.292188929512, 12878.871211287955, 16017.58848126905, 16463.83536164623, 16902.492925212264, 18632.813671863107, 19055.80944190568, 6580.736770529125, 7497.400207983457, 7667.00328957232, 7703.653335599912, 7759.72840950564, 875.671623141322, 940.8682784410188, 953.080277532171, 1009.3388830316155, 1038.807820553185, 28375.006874759285, 29519.55720664192, 29729.70288177859, 30076.947306844155, 30413.847930760647, 1016.5273147986074, 1075.1452595186845, 1099.6700989703056, 1145.410113791398, 1149.4367759797806, 15256.160911149993, 15390.230488475532, 15421.460248891235, 15491.99302045386, 15886.205689715467, 522831.0, 585612.0, 587943.0, 588465.6343901041, 589107.0, 49610.75194624004, 50123.24815127776, 50179.0, 52708.0, 55246.723766380914, 1358.1918758338772, 1373.3888419653158, 1394.8186735724291, 1395.5324966136254, 1398.4755716606273, 25900.8704956474, 29676.446654385414, 30818.383632327677, 31675.0, 31799.205019874687, 49047.264291034946, 49496.0, 49535.0, 49539.752301238266, 49581.752091239316, 486274.0, 489256.0, 541069.0, 541315.2934235461, 641442.0, 38013.61986380101, 38477.0, 39235.2152956945, 40193.39709904412, 40615.1876962465, 44694.43569475053, 44764.0, 45500.77249613731, 45589.316160258284, 45809.77095114503, 35465.468017979234, 36402.4539631911, 36863.0, 37254.44118338173, 38444.0, 37033.259334813694, 37967.0, 38033.619663804115, 38238.80880595579, 38580.807095965465, 37853.62146378611, 38595.80702096471, 38807.61192388153, 38854.0, 38884.0, 29517.11448656544, 30505.694943050286, 32363.0, 32907.848225312, 33244.1688957778, 24844.37889052788, 25038.74961250364, 25268.747312527375, 25431.60756950239, 26002.609960850223], "value": 20274.097512752498, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.66103049526714, 348.8691740597276, 360.778121455305, 369.26884768159044, 371.75835706790576, 15348.923255383721, 16253.83746162538, 20191.293304734336, 20265.797342026577, 20920.0, 14659.633509162277, 18892.622147557042, 18931.52671183221, 19101.23595056196, 19876.20495180195, 4712.646551508634, 5506.889862202754, 5508.283923089993, 5511.200875873016, 5518.889622207574, 5164.896702065957, 5224.45143259958, 6031.788887388942, 6074.088886667013, 6079.331273559905, 5771.68128326212, 6031.909521357183, 6043.8186854394435, 6043.939560604404, 6049.546284028694, 5822.767089316422, 5954.910676339859, 5959.582829201958, 5960.046392577166, 5962.34414214436, 4470.240059189946, 5023.547880690719, 5250.05499010178, 5253.658512196695, 5268.736563171864, 24152.0, 24790.5041899161, 24816.751832481623, 25099.37251568707, 25159.24522264335, 2151.440625437382, 2188.715466989281, 2213.8007579317778, 2315.120254303354, 2326.8487548309304, 9456.52717364129, 9974.002599739968, 10139.036791504795, 10319.019693129141, 10411.947940260327, 1694.3561446650317, 1734.9826501734947, 1759.3314540474537, 1768.3103589600066, 1823.9088045597666, 122027.16959245618, 122531.3873430636, 122950.0, 124370.0, 125708.0, 9218.953905230564, 11106.722331941604, 11415.029722473517, 11508.769824603462, 11652.70868228284, 14491.20298383578, 14511.492097776738, 14550.927245363706, 14551.126932383879, 14565.92717036408, 8109.351251899834, 8417.158284171534, 8423.70517031901, 8486.830263394697, 8528.360372972053, 6713.0, 8270.958645206736, 8277.04813946392, 8440.493570385797, 8474.406791524658, 4471.821127154877, 4485.430099465161, 4486.641068714495, 4507.5943165115295, 4548.499665036843, 1583.3112596020767, 1792.6059863707283, 1841.5948491331885, 1861.6462872054267, 1917.1625993529653, 781.5115552779513, 795.0419744208215, 797.2266901105918, 852.7740148860557, 979.5787811241235, 19871.0, 20139.39581812548, 20940.89529552373, 21029.0, 21078.262260821106, 788.8304014636881, 899.964001439935, 903.2412773270485, 928.6656803550717, 1041.9270651054505, 8890.466572005571, 10557.736056598493, 10937.671869843918, 11002.449877506253, 11097.889021109848, 576254.0, 588854.0, 588986.0, 589518.0524097438, 596459.0, 125913.0, 127612.72387276008, 128218.99999999814, 136468.9529657056, 136594.0, 957.7797106665506, 967.8644989701447, 1014.4826138669332, 1014.822406078948, 1016.8576399304103, 14023.929880350533, 15367.615809604402, 17638.0, 17900.104994750473, 20894.47763805917, 58423.0, 58575.121373180285, 58836.82326353363, 59178.0, 60600.0, 78160.82758758737, 80357.0, 82058.0, 84507.5774621123, 85637.0, 6937.410320122846, 7213.6032518212005, 7249.855002900018, 7513.549187048795, 7913.604319783873, 11396.829047564459, 12724.363781811058, 12764.61706148817, 13112.278824664734, 13118.737625247251, 11072.723181920517, 11112.555497780322, 11250.156238281998, 11351.829722554003, 11446.37044962535, 10643.840342394715, 11069.391183484664, 11269.0, 11329.830052549054, 11491.965723084963, 10090.192784577363, 10388.896111038794, 10541.472926353805, 11297.71755706114, 11947.940260298643, 7221.386182174574, 7273.854522909618, 7378.778636640909, 7449.292317229854, 7590.316871481593, 9130.589123489592, 10185.624940632919, 10472.842907356548, 11111.944365285284, 11520.481578329163], "value": 9303.049773418876, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1487.464512775401, 1520.7718842173672, 1713.1947984447313, 1880.5110671225477, 1906.8665193436457, 8261.173882611743, 8797.42816716913, 8804.471731696094, 8804.8679269811, 8809.823803523926, 7586.431017673669, 7804.648790804413, 8281.54451505167, 8506.319494440446, 8603.0, 2298.5977453945534, 2333.614953532665, 2334.9182778602753, 2339.94150146246, 2342.2856028911174, 2301.666258392532, 2323.0707716913203, 2343.625019996801, 2344.42561572415, 2353.8116950643907, 2321.7446080931086, 2333.9649905251435, 2342.278425357361, 2350.3888988862846, 2351.2828587280874, 2279.908803647852, 2299.5975704251773, 2307.3424074138875, 2308.8845557722047, 2314.201600447843, 2235.7205349331334, 2241.0923575951765, 2242.215224671352, 2242.5963326601204, 2249.3701763506247, 8603.612837422268, 8626.309895208431, 8695.913040869573, 8704.0, 8746.86879696805, 5114.872128196787, 5263.526282634582, 5264.131418315973, 5266.1574148136115, 5273.736313184326, 3416.4875268709893, 3461.6192218855904, 3543.20277937462, 3591.28174365128, 3597.5503062117236, 1093.4587379247323, 1110.6723516562633, 1768.8584913206912, 1830.7162389829464, 1870.7661542307212, 11171.497282622302, 11226.494807733672, 11257.0, 11257.043151332147, 11260.930211629964, 3850.8652197172964, 4153.279835821401, 4465.151621191963, 4495.865124046219, 4511.954880451219, 8875.511846848356, 8881.42270752399, 8887.777805554913, 8892.3330750194, 8916.509591972374, 5111.437741848394, 5143.871403214949, 5146.716930568779, 5148.330717006766, 5148.4851514848215, 3672.7245456590326, 4424.292113261927, 4782.856514304576, 4837.201861692782, 4840.6127509799135, 3597.111516453883, 3638.9090272742865, 3706.8887933362043, 3742.0831896185064, 3751.4747935289083, 1122.1135303110614, 1129.4014172488594, 1130.4912789244709, 1138.698244965085, 1138.9578535639796, 801.8276070644839, 826.6362800367818, 875.2472873328932, 898.9235914947303, 914.5015966298383, 5543.722813859372, 5587.664740115606, 5832.591718579659, 5835.174239258052, 6009.849753756103, 375.8459031797005, 436.85802114312975, 444.0652426641937, 445.35423635727676, 447.74702293204234, 3871.109644781716, 3879.573246942834, 3951.8024098794945, 3997.760134391946, 4037.4953130858644, 9864.816222053256, 9929.30494865367, 10285.897141028643, 10595.576176952989, 10635.680929572125, 10478.266521343585, 10510.842337364946, 10511.001454861776, 10511.579536818595, 10520.473976301155, 7756.844863102707, 7841.019872515935, 7936.761897143095, 8065.354771618257, 8068.636911339004, 5754.309482862083, 5817.055930549795, 5906.438888305604, 5962.791302304398, 6078.3921607838865, 8301.294389976678, 9851.310408271353, 9891.35706179096, 9891.406515609087, 9904.851427228454, 9399.0, 9851.45816980073, 9876.40741555509, 10040.146587539848, 10072.496375181358, 7346.595937223504, 7369.8157546061775, 7380.889286660812, 7403.259674032769, 7446.032015837906, 7974.202579741979, 8032.076311224284, 8051.0, 8052.154523775153, 8087.3125784308995, 6136.723847426966, 7163.892541611775, 7259.709611615476, 7330.157031941182, 7424.1462231844025, 7186.496945213785, 7215.953686715292, 7237.421006319377, 7275.417966562556, 7299.52553084069, 6808.319168083152, 6871.759488417882, 6911.930880691129, 6913.792586222421, 6930.584164949917, 4925.581325587365, 4959.677620954626, 4998.375203099613, 5013.623978201576, 5077.974610126926, 4906.423874557319, 4934.407871055353, 5009.348784657971, 5153.922691159561, 5155.9742201290255], "value": 4419.7159476505585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.47792690048254, 299.5626385477203, 307.8845432962639, 312.2988889942079, 315.46686100490183, 4744.478107408187, 5077.851731926211, 5230.450802665713, 5269.262303277545, 5299.8940021199605, 4775.6418268629905, 4779.617630589553, 5228.849653076317, 5239.192478594879, 5267.947320526793, 3885.941710874339, 4045.4538637283886, 4050.0, 4054.0067683417606, 4062.4109504121884, 3504.983554769114, 3560.376934036546, 3830.578636349999, 3850.249201405727, 4039.474868267121, 3495.3009398120353, 3709.684676802475, 3948.072203032285, 3959.3269144245405, 4030.2141082488924, 3704.3887758519813, 3724.1434470071763, 3984.740991835536, 3989.5412027616903, 4019.4573732546023, 3501.299740052, 3510.7542472026967, 3513.2095278562374, 3519.964800351989, 3525.6121826599056, 4509.684322097454, 4689.0, 4998.0, 4999.600031997432, 5039.319691841628, 4530.29780384041, 4557.931631025537, 4558.8632341029825, 4569.817207311704, 4574.954250457519, 4545.477270113946, 4645.188376533155, 4968.30443737877, 5331.786728530854, 5521.861953451196, 3318.1206980150278, 3468.8899552143325, 3599.6220396858216, 3768.2463507298376, 3788.9621103788886, 4217.641500472433, 5569.248151499536, 5629.774809007676, 5645.237892884449, 5778.768849246025, 3732.432378401078, 3821.464994900716, 3827.196288779332, 3832.5784163741964, 3833.3291673956946, 3228.322052369029, 3869.3422118239405, 3893.3575959966856, 3893.377059670439, 3904.902377440587, 3761.7774223376964, 4490.595846373842, 4503.279475284005, 4506.580427165432, 4515.887102822456, 4842.225243960949, 5419.566434685215, 5543.2793736814765, 5566.2207290979295, 5592.853465039657, 1141.007323628432, 1322.332222227788, 1322.4908410261926, 1323.2259128409796, 1328.7509740843577, 676.1548064918851, 720.7926722739495, 733.9944276341446, 736.9535259930911, 781.394234847394, 3755.4179102239195, 4328.415663885336, 4395.84603544261, 4404.11917616478, 4411.470623525198, 3939.4484772131923, 3963.6630886374983, 4257.723247988871, 4761.833380801797, 4779.354787103696, 3832.850144956502, 3983.2515564260566, 4011.3782363733667, 4313.956860431418, 4359.869203923824, 2138.3371154941706, 2232.8596063507075, 2233.6761169630313, 2488.9128880489097, 2491.798039519209, 4624.0, 4645.907081858344, 4795.184818580849, 4799.088173247142, 4817.590504807061, 4138.082651730523, 4637.240841699184, 4736.8578942631775, 4752.1921273383605, 4823.0, 3670.192048416583, 4314.697971142053, 4820.4456487503685, 4840.782164802522, 4928.507149285043, 4601.67788254819, 4608.723476591415, 4625.953740462553, 4645.674802763774, 4672.602828759457, 3703.8888833335045, 4514.68397212192, 4554.795034223402, 4591.334256532851, 4706.0, 4621.653375996746, 4851.2105539314325, 4880.853574392774, 5534.335879694462, 5918.467337939606, 3524.5946716126946, 3748.9128152835374, 3838.136419305634, 3850.4609354689806, 3854.1520865409566, 4493.438320209973, 5017.774200161075, 5134.05020071288, 5768.307803063659, 5769.711514424346, 5136.635503745053, 5399.757010934439, 5565.805196818092, 5601.207613563621, 5662.433756624305, 5520.585956053393, 5524.66851988882, 5533.723313834212, 5554.361248456479, 5599.496045355774, 4604.332371806003, 5018.573421259234, 5155.690658560498, 5456.972715136399, 5473.945260547503, 4244.639205667553, 4997.525235102659, 5528.308961379827, 5568.192612071308, 5604.607677462538, 4340.8697739067875, 5480.835574932758, 5487.725613719378, 5494.615376923577, 5499.202615620633], "value": 3785.3530631373505, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.9462437629364, 776.8365103187624, 784.5528049012062, 793.773774474275, 798.7884546858298, 14994.250287485624, 15816.44642437515, 15876.28556714949, 15893.2848021839, 16222.107784071872, 14302.856971430283, 14642.780358294634, 14668.779968300483, 14677.119372837637, 14678.926605366985, 669.725412580842, 820.1183727492954, 845.6701886264361, 847.1020718038868, 848.9405741598091, 684.9520533562653, 827.2554700769296, 834.3033566971586, 847.9448835825682, 850.5407080176698, 880.454118446564, 882.5322579033123, 882.7219425880859, 884.0452311503593, 884.7876509637705, 702.8980797784344, 877.8770972063916, 878.376352789517, 878.9340799440067, 882.6689991253312, 639.3702203329716, 652.1359199061263, 660.21764209412, 660.8281846719888, 660.9504287178481, 14434.927825360912, 14556.344964476624, 14563.70872582553, 14563.92718036403, 14600.342984565614, 13219.735605287937, 13583.320833958263, 13715.725685486233, 13772.898168146525, 13999.790003149961, 12301.077419193507, 12446.190997585218, 12493.25040497573, 12511.874881251162, 12948.417321220473, 4435.290353543418, 4467.262901621229, 4492.640588752892, 4566.566176213254, 4591.058832939239, 107134.0, 110318.44840775825, 110544.4472777639, 110560.0, 110819.0, 10141.949290253502, 10745.355278683304, 10747.355158690347, 10846.674599761862, 11088.168387370979, 19998.800011999694, 20710.378688639365, 20918.267860624805, 20921.84929828874, 20991.0, 7528.209537998431, 7589.051368578927, 7672.232776722283, 7723.691052357843, 7773.533587984739, 4284.91430171401, 4326.784238356491, 4337.544557821383, 4360.258756011485, 4480.619147372445, 3385.6445073267446, 3510.0, 3511.3152935177395, 3516.6307537708685, 3517.6457064030533, 636.9363063693594, 640.484410049905, 652.6019128331714, 657.4050484311706, 658.8221180281297, 563.2233090862089, 578.5805291163956, 612.2744547710888, 617.9598326108788, 624.9125122482856, 8823.955880220687, 8838.337124715672, 8840.602172902234, 9036.502992335354, 9057.320700947455, 577.6863661037518, 581.4882903045293, 608.9086637004397, 612.886615976037, 614.2536817766435, 4339.349097635317, 4376.803043863034, 4401.383806267125, 4434.11317736454, 4467.396901418332, 152680.0, 156655.0, 157370.0, 157790.42209577988, 162338.0, 132715.33642331918, 134811.0, 135654.0, 140400.0, 143432.0, 95309.0469095314, 116102.0, 116809.0, 118736.0, 120283.0, 8181.263686268265, 8421.578921054046, 8497.2777313929, 8747.256483198998, 8790.648374064966, 48901.5109848897, 57704.42295576991, 64065.0, 65868.69459568892, 66710.0, 74900.0, 85671.0, 94954.0, 98808.01191988276, 100478.99521004697, 10244.129249013618, 10408.531616077144, 10520.316179448311, 10536.209784266057, 11153.163512736415, 11939.164258501822, 11966.401679915794, 12059.75880482368, 12216.96155826729, 12444.875551244371, 11283.943580282374, 11476.655700329004, 11642.825357619811, 11865.881341186478, 11908.345041022827, 10142.188624910137, 10866.345339350264, 11140.944295278472, 11470.770584588428, 12018.819717704417, 11258.605948791754, 11268.7746245073, 11317.603883864025, 11621.593244236412, 11724.765504690029, 8898.976617689048, 9159.587818548049, 9261.629534818532, 9499.905000950179, 9815.705528834147, 9471.47906865129, 10243.821960474343, 10752.462376881282, 10910.945445272722, 11256.380899050631], "value": 7528.5244166619095, "unit": "score"}], "name": "dromaeo_css", "value": 7123.3540929071205, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 113.0, 168.0, 136.0, 113.0, 113.0, 114.0, 115.0, 124.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 129.0, 163.0, 107.0, 130.0, 131.0, 107.0, 160.0, 107.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 184.0, 185.0, 184.0, 180.0, 185.0, 182.0, 185.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 76.0, 159.0, 61.0, 78.0, 80.0, 87.0, 147.0, 58.0], "value": 90.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 135.0, 69.0, 74.0, 80.0, 120.0, 69.0, 69.0, 116.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 90.0, 85.0, 91.0, 88.0, 90.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 109.0, 149.0, 88.0, 145.0, 149.0, 97.0, 94.0], "value": 111.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 138.0, 86.0, 84.0, 85.0, 86.0, 87.0, 84.0, 84.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 53.0, 55.0, 53.0, 54.0, 54.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 77.0, 79.0, 73.0, 71.0, 72.0, 71.0, 72.0, 73.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 86.0, 88.0, 85.0, 84.0, 89.0, 89.0, 110.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 136.0, 135.0, 140.0, 152.0, 151.0, 154.0, 147.0, 157.0, 141.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 167.0, 170.0, 170.0, 169.0, 170.0, 166.0, 171.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 70.0, 85.0, 84.0, 84.0, 85.0, 67.0, 67.0, 67.0], "value": 74.3, "unit": "score"}], "name": "kraken", "value": 1511.2, "alertThreshold": 2.0}]} 11:26:06 INFO - Return code: 0 11:26:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:26:06 INFO - # TBPL SUCCESS # 11:26:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:26:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:26:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:26:06 INFO - 2016-09-03 11:26:06.211 screenresolution[2017:18503] starting screenresolution argv=screenresolution get 11:26:06 INFO - 2016-09-03 11:26:06.213 screenresolution[2017:18503] Display 0: 1600x1200x32@60 11:26:06 INFO - 2016-09-03 11:26:06.219 screenresolution[2018:18506] starting screenresolution argv=screenresolution list 11:26:06 INFO - Available Modes on Display 0 11:26:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:26:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:26:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:26:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:26:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:26:06 INFO - Intel Iris: 11:26:06 INFO - Chipset Model: Intel Iris 11:26:06 INFO - Type: GPU 11:26:06 INFO - Bus: Built-In 11:26:06 INFO - VRAM (Dynamic, Max): 1536 MB 11:26:06 INFO - Vendor: Intel (0x8086) 11:26:06 INFO - Device ID: 0x0a2e 11:26:06 INFO - Revision ID: 0x0009 11:26:06 INFO - Displays: 11:26:06 INFO - Display: 11:26:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:26:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:26:06 INFO - Display Serial Number: 1600x1200 60 11:26:06 INFO - Main Display: Yes 11:26:06 INFO - Mirror: Off 11:26:06 INFO - Online: Yes 11:26:06 INFO - Rotation: Supported 11:26:06 INFO - Return code: 0 11:26:06 INFO - Running post-action listener: _resource_record_post_action 11:26:06 INFO - [mozharness: 2016-09-03 18:26:06.458112Z] Finished run-tests step (success) 11:26:06 INFO - Running post-run listener: _resource_record_post_run 11:26:06 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 204800; Write bytes: 704873472; Read time: 1; Write time: 1935 11:26:06 INFO - TinderboxPrint: CPU usage
53.5% 11:26:06 INFO - TinderboxPrint: I/O read bytes / time
204,800 / 1 11:26:06 INFO - TinderboxPrint: I/O write bytes / time
704,873,472 / 1,935 11:26:06 INFO - TinderboxPrint: CPU idle
2,253.1 (46.5%) 11:26:06 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 11:26:06 INFO - TinderboxPrint: CPU user
1,627.8 (33.6%) 11:26:06 INFO - TinderboxPrint: Swap in / out
405,004,288 / 0 11:26:06 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391688704; Write bytes: 386765824; Read time: 29480; Write time: 785 11:26:06 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 204800; Write bytes: 296058880; Read time: 1; Write time: 1098 11:26:06 INFO - Running post-run listener: _upload_blobber_files 11:26:06 INFO - Blob upload gear active. 11:26:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:26:06 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 11:26:06 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'] 11:26:06 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 11:26:07 INFO - (blobuploader) - INFO - Open directory for files ... 11:26:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:26:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:26:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:08 INFO - (blobuploader) - INFO - Done attempting. 11:26:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:09 INFO - (blobuploader) - INFO - Done attempting. 11:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:26:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:11 INFO - (blobuploader) - INFO - Done attempting. 11:26:11 INFO - (blobuploader) - INFO - Iteration through files over. 11:26:11 INFO - Return code: 0 11:26:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:26:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:26:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5075304d13b3c0c1545e2b402368cd3237ab809180539618a27f2f01c3eade862e1ef18adb2a656b0b4c555ed054c0b3e48b5179951a1dc043833f14d048cf51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfef7ae2e9d68c4d7f2826a7e22f811f47300fb07c601202d9784052a23d2f3d95a14e4aeeafc43dd0279726eab1410c0777051f00b71ef39efe80caf5cb0b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd1976ea270ab649b11262f854214d941974b704d620a4e890ceebd2aef560e71494115b4806fe20d41bfda51bdbb78a21a2eb354e21d426b2d8e76401d02107"} 11:26:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:26:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:26:11 INFO - Contents: 11:26:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5075304d13b3c0c1545e2b402368cd3237ab809180539618a27f2f01c3eade862e1ef18adb2a656b0b4c555ed054c0b3e48b5179951a1dc043833f14d048cf51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfef7ae2e9d68c4d7f2826a7e22f811f47300fb07c601202d9784052a23d2f3d95a14e4aeeafc43dd0279726eab1410c0777051f00b71ef39efe80caf5cb0b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd1976ea270ab649b11262f854214d941974b704d620a4e890ceebd2aef560e71494115b4806fe20d41bfda51bdbb78a21a2eb354e21d426b2d8e76401d02107"} 11:26:11 INFO - Running post-run listener: copy_logs_to_upload_dir 11:26:11 INFO - Copying logs to upload dir... 11:26:11 INFO - mkdir: /builds/slave/test/build/upload/logs 11:26:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.025869 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-09-03 11:26:11.230577) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 11:26:11.234448) ========= 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.dzyvi8D8K2/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.izFB9BSXnV/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5075304d13b3c0c1545e2b402368cd3237ab809180539618a27f2f01c3eade862e1ef18adb2a656b0b4c555ed054c0b3e48b5179951a1dc043833f14d048cf51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfef7ae2e9d68c4d7f2826a7e22f811f47300fb07c601202d9784052a23d2f3d95a14e4aeeafc43dd0279726eab1410c0777051f00b71ef39efe80caf5cb0b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd1976ea270ab649b11262f854214d941974b704d620a4e890ceebd2aef560e71494115b4806fe20d41bfda51bdbb78a21a2eb354e21d426b2d8e76401d02107"} build_url:https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010996 build_url: 'https://queue.taskcluster.net/v1/task/DLGhoWsZSnG-wMeDgDo6Zw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5075304d13b3c0c1545e2b402368cd3237ab809180539618a27f2f01c3eade862e1ef18adb2a656b0b4c555ed054c0b3e48b5179951a1dc043833f14d048cf51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dfef7ae2e9d68c4d7f2826a7e22f811f47300fb07c601202d9784052a23d2f3d95a14e4aeeafc43dd0279726eab1410c0777051f00b71ef39efe80caf5cb0b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd1976ea270ab649b11262f854214d941974b704d620a4e890ceebd2aef560e71494115b4806fe20d41bfda51bdbb78a21a2eb354e21d426b2d8e76401d02107"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 11:26:11.268105) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:26:11.268464) ========= 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.dzyvi8D8K2/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.izFB9BSXnV/Listeners TMPDIR=/var/folders/5j/jj83rdp553n_19cbd8hk96b400000w/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.005302 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 11:26:11.316833) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 11:26:11.317161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 11:26:11.317471) ========= ========= Total master_lag: 11.19 =========