builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0123 starttime: 1496166845.87 results: success (0) buildid: 20170530100116 builduid: b3a9fa5fab3c472a8205e5f17bfd4f70 revision: 1bf2d9f8a5cb68370f9a797890beb14769f4144b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.867602) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.868078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.868369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.899092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.899426) ========= 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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-30 10:54:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2017-05-30 10:54:05 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.077205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.999178) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:05.999489) ========= 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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.022463 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:06.047730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 10:54:06.048092) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1bf2d9f8a5cb68370f9a797890beb14769f4144b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1bf2d9f8a5cb68370f9a797890beb14769f4144b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-30 10:54:06,124 truncating revision to first 12 chars 2017-05-30 10:54:06,124 Setting DEBUG logging. 2017-05-30 10:54:06,125 attempt 1/10 2017-05-30 10:54:06,125 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1bf2d9f8a5cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 10:54:07,203 unpacking tar archive at: mozilla-beta-1bf2d9f8a5cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.343144 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 10:54:07.413970) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:07.414294) ========= script_repo_revision: 1bf2d9f8a5cb68370f9a797890beb14769f4144b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:07.414683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:07.414963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:07.433862) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-30 10:54:07.434190) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:54:07 INFO - MultiFileLogger online at 20170530 10:54:07 in /builds/slave/test 10:54:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:54:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:54:07 INFO - {'append_to_log': False, 10:54:07 INFO - 'base_work_dir': '/builds/slave/test', 10:54:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:54:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:54:07 INFO - 'branch': 'Mozilla-Beta', 10:54:07 INFO - 'buildbot_json_path': 'buildprops.json', 10:54:07 INFO - 'config_files': ('talos/mac_config.py',), 10:54:07 INFO - 'default_actions': ('clobber', 10:54:07 INFO - 'read-buildbot-config', 10:54:07 INFO - 'download-and-extract', 10:54:07 INFO - 'populate-webroot', 10:54:07 INFO - 'create-virtualenv', 10:54:07 INFO - 'install', 10:54:07 INFO - 'run-tests'), 10:54:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:54:07 INFO - 'download_minidump_stackwalk': True, 10:54:07 INFO - 'download_symbols': 'ondemand', 10:54:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:54:07 INFO - 'tooltool.py': '/tools/tooltool.py', 10:54:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:54:07 INFO - '/tools/misc-python/virtualenv.py')}, 10:54:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:54:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:54:07 INFO - 'gecko_profile': False, 10:54:07 INFO - 'gecko_profile_interval': 0, 10:54:07 INFO - 'installer_path': 'installer.exe', 10:54:07 INFO - 'log_level': 'info', 10:54:07 INFO - 'log_name': 'talos', 10:54:07 INFO - 'log_to_console': True, 10:54:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:54:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:54:07 INFO - 'opt_config_files': (), 10:54:07 INFO - 'pip_index': False, 10:54:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:54:07 INFO - 'cmd': ('bash', 10:54:07 INFO - '-c', 10:54:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:54:07 INFO - 'enabled': True, 10:54:07 INFO - 'halt_on_failure': False, 10:54:07 INFO - 'name': 'check_screen_resolution'},), 10:54:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:54:07 INFO - 'cmd': ('bash', 10:54:07 INFO - '-c', 10:54:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:54:07 INFO - 'enabled': True, 10:54:07 INFO - 'halt_on_failure': False, 10:54:07 INFO - 'name': 'check_screen_resolution'},), 10:54:07 INFO - 'run_cmd_checks_enabled': True, 10:54:07 INFO - 'suite': 'dromaeojs', 10:54:07 INFO - 'system_bits': '32', 10:54:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:54:07 INFO - 'title': 't-yosemite-r7-0123', 10:54:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:54:07 INFO - 'use_talos_json': True, 10:54:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:54:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:54:07 INFO - 'work_dir': 'build'} 10:54:07 INFO - [mozharness: 2017-05-30 17:54:07.639161Z] Running clobber step. 10:54:07 INFO - Running pre-action listener: _resource_record_pre_action 10:54:07 INFO - Running main action method: clobber 10:54:07 INFO - rmtree: /builds/slave/test/build 10:54:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:54:09 INFO - Running post-action listener: _resource_record_post_action 10:54:09 INFO - [mozharness: 2017-05-30 17:54:09.794897Z] Finished clobber step (success) 10:54:09 INFO - [mozharness: 2017-05-30 17:54:09.795129Z] Running read-buildbot-config step. 10:54:09 INFO - Running pre-action listener: _resource_record_pre_action 10:54:09 INFO - Running main action method: read_buildbot_config 10:54:09 INFO - Using buildbot properties: 10:54:09 INFO - { 10:54:09 INFO - "project": "", 10:54:09 INFO - "product": "firefox", 10:54:09 INFO - "script_repo_revision": "production", 10:54:09 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 10:54:09 INFO - "repository": "", 10:54:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 10:54:09 INFO - "buildid": "20170530100116", 10:54:09 INFO - "pgo_build": "False", 10:54:09 INFO - "basedir": "/builds/slave/test", 10:54:09 INFO - "buildnumber": 10, 10:54:09 INFO - "slavename": "t-yosemite-r7-0123", 10:54:09 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:54:09 INFO - "platform": "yosemite_r7", 10:54:09 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:54:09 INFO - "branch": "mozilla-beta", 10:54:09 INFO - "repo_path": "releases/mozilla-beta", 10:54:09 INFO - "revision": "1bf2d9f8a5cb68370f9a797890beb14769f4144b", 10:54:09 INFO - "stage_platform": "macosx64", 10:54:09 INFO - "builduid": "b3a9fa5fab3c472a8205e5f17bfd4f70", 10:54:09 INFO - "slavebuilddir": "test" 10:54:09 INFO - } 10:54:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:54:09 INFO - Running post-action listener: _resource_record_post_action 10:54:09 INFO - [mozharness: 2017-05-30 17:54:09.800636Z] Finished read-buildbot-config step (success) 10:54:09 INFO - [mozharness: 2017-05-30 17:54:09.800859Z] Running download-and-extract step. 10:54:09 INFO - Running pre-action listener: _resource_record_pre_action 10:54:09 INFO - Running main action method: download_and_extract 10:54:09 INFO - mkdir: /builds/slave/test/build/tests 10:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:09 INFO - trying https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:54:09 INFO - Downloading https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:54:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:54:11 INFO - Downloaded 1287 bytes. 10:54:11 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:54:11 INFO - Using the following test package requirements: 10:54:11 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:54:11 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:54:11 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:54:11 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:54:11 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:54:11 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:54:11 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:54:11 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:54:11 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:54:11 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:54:11 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:11 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:54:11 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:54:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:54:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:54:11 INFO - Fetch https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:54:12 INFO - Expected file size: 51393243 10:54:12 INFO - Obtained file size: 51393243 10:54:29 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:54:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:54:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:54:29 INFO - Fetch https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:54:30 INFO - Expected file size: 51393243 10:54:30 INFO - Obtained file size: 51393243 10:54:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:54:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:54:46 INFO - Fetch https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:54:47 INFO - Expected file size: 14404461 10:54:47 INFO - Obtained file size: 14404461 10:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:47 INFO - trying https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:54:47 INFO - Downloading https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:54:49 INFO - Downloaded 59644154 bytes. 10:54:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:54:49 INFO - mkdir: /builds/slave/test/properties 10:54:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:54:49 INFO - Writing to file /builds/slave/test/properties/build_url 10:54:49 INFO - Contents: 10:54:49 INFO - build_url:https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:54:49 INFO - Running post-action listener: _resource_record_post_action 10:54:49 INFO - Running post-action listener: set_extra_try_arguments 10:54:49 INFO - [mozharness: 2017-05-30 17:54:49.783778Z] Finished download-and-extract step (success) 10:54:49 INFO - [mozharness: 2017-05-30 17:54:49.783906Z] Running populate-webroot step. 10:54:49 INFO - Running pre-action listener: _resource_record_pre_action 10:54:49 INFO - Running main action method: populate_webroot 10:54:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:54:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:54:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:54:49 INFO - '--tppagecycles', 10:54:49 INFO - '7'], 10:54:49 INFO - 'tests': ['tsvgm']}}, 10:54:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:54:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:54:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:54:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:54:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:54:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:54:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['damp', 'tps']}, 10:54:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'tests': ['damp', 'tps']}, 10:54:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['dromaeo_dom']}, 10:54:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:54:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['basic_compositor_video']}, 10:54:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:54:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['a11yr', 10:54:49 INFO - 'ts_paint', 10:54:49 INFO - 'tpaint', 10:54:49 INFO - 'sessionrestore', 10:54:49 INFO - 'sessionrestore_no_auto_restore', 10:54:49 INFO - 'tabpaint']}, 10:54:49 INFO - 'other-e10s': {'tests': ['a11yr', 10:54:49 INFO - 'ts_paint', 10:54:49 INFO - 'tpaint', 10:54:49 INFO - 'sessionrestore', 10:54:49 INFO - 'sessionrestore_no_auto_restore', 10:54:49 INFO - 'tabpaint']}, 10:54:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:54:49 INFO - 'ts_paint', 10:54:49 INFO - 'tpaint', 10:54:49 INFO - 'sessionrestore', 10:54:49 INFO - 'sessionrestore_no_auto_restore', 10:54:49 INFO - 'tabpaint']}, 10:54:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:54:49 INFO - 'ts_paint', 10:54:49 INFO - 'tpaint', 10:54:49 INFO - 'sessionrestore', 10:54:49 INFO - 'sessionrestore_no_auto_restore', 10:54:49 INFO - 'tabpaint']}, 10:54:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['a11yr', 10:54:49 INFO - 'ts_paint', 10:54:49 INFO - 'tpaint', 10:54:49 INFO - 'sessionrestore', 10:54:49 INFO - 'sessionrestore_no_auto_restore', 10:54:49 INFO - 'tabpaint']}, 10:54:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['a11yr', 10:54:49 INFO - 'ts_paint', 10:54:49 INFO - 'tpaint', 10:54:49 INFO - 'sessionrestore', 10:54:49 INFO - 'sessionrestore_no_auto_restore', 10:54:49 INFO - 'tabpaint']}, 10:54:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['tsvgx', 10:54:49 INFO - 'tsvgr_opacity', 10:54:49 INFO - 'tart', 10:54:49 INFO - 'tscrollx', 10:54:49 INFO - 'cart', 10:54:49 INFO - 'tsvg_static']}, 10:54:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:54:49 INFO - 'tsvgr_opacity', 10:54:49 INFO - 'tart', 10:54:49 INFO - 'tscrollx', 10:54:49 INFO - 'cart', 10:54:49 INFO - 'tsvg_static']}, 10:54:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'talos_options': ['--disable-e10s'], 10:54:49 INFO - 'tests': ['tp5o']}, 10:54:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:54:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'talos_options': ['--disable-e10s', 10:54:49 INFO - '--xperf_path', 10:54:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:54:49 INFO - 'tests': ['tp5n']}, 10:54:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:54:49 INFO - 'talos_options': ['--xperf_path', 10:54:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:54:49 INFO - 'tests': ['tp5n']}}, 10:54:49 INFO - 'talos.zip': {'path': '', 10:54:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:54:49 INFO - Running post-action listener: _resource_record_post_action 10:54:49 INFO - [mozharness: 2017-05-30 17:54:49.804514Z] Finished populate-webroot step (success) 10:54:49 INFO - [mozharness: 2017-05-30 17:54:49.804647Z] Running create-virtualenv step. 10:54:49 INFO - Running pre-action listener: _resource_record_pre_action 10:54:49 INFO - Running main action method: create_virtualenv 10:54:49 INFO - Creating virtualenv /builds/slave/test/build/venv 10:54:49 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 10:54:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:54:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:54:49 INFO - Using real prefix '/tools/python27' 10:54:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:54:50 INFO - Installing distribute.............................................................................................................................................................................................done. 10:54:53 INFO - Installing pip.................done. 10:54:53 INFO - Return code: 0 10:54:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:54:53 INFO - Reading from file tmpfile_stdout 10:54:53 INFO - Output received: 10:54:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:54:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54: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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:54:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:54:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:53 INFO - 'HOME': '/Users/cltbld', 10:54:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:53 INFO - 'LOGNAME': 'cltbld', 10:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:53 INFO - 'MOZ_NO_REMOTE': '1', 10:54:53 INFO - 'NO_EM_RESTART': '1', 10:54:53 INFO - 'PAGER': '/bin/cat', 10:54:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:53 INFO - 'PWD': '/builds/slave/test', 10:54:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:53 INFO - 'SHELL': '/bin/bash', 10:54:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:54:53 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:54:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:53 INFO - 'USER': 'cltbld', 10:54:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:53 INFO - 'XPC_FLAGS': '0x0', 10:54:53 INFO - 'XPC_SERVICE_NAME': '0', 10:54:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:54 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 10:54:54 INFO - Cleaning up... 10:54:54 INFO - Return code: 0 10:54:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:54:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:54:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:54:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:54:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:54 INFO - 'HOME': '/Users/cltbld', 10:54:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:54 INFO - 'LOGNAME': 'cltbld', 10:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:54 INFO - 'MOZ_NO_REMOTE': '1', 10:54:54 INFO - 'NO_EM_RESTART': '1', 10:54:54 INFO - 'PAGER': '/bin/cat', 10:54:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:54 INFO - 'PWD': '/builds/slave/test', 10:54:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:54 INFO - 'SHELL': '/bin/bash', 10:54:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:54:54 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:54:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:54 INFO - 'USER': 'cltbld', 10:54:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:54 INFO - 'XPC_FLAGS': '0x0', 10:54:54 INFO - 'XPC_SERVICE_NAME': '0', 10:54:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:54 INFO - Downloading/unpacking psutil>=3.1.1 10:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:54:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:54:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:54:56 INFO - Installing collected packages: psutil 10:54:56 INFO - Running setup.py install for psutil 10:54:56 INFO - building 'psutil._psutil_osx' extension 10:54:56 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 10:54:56 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 10:54:56 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 10:54:56 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 10:54:56 INFO - building 'psutil._psutil_posix' extension 10:54:56 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 10:54:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:54:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:54:56 INFO - ^ 10:54:56 INFO - 1 warning generated. 10:54:56 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 10:54:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:54:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:54:57 INFO - Successfully installed psutil 10:54:57 INFO - Cleaning up... 10:54:57 INFO - Return code: 0 10:54:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:57 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:54:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:54:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:54:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:57 INFO - 'HOME': '/Users/cltbld', 10:54:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:57 INFO - 'LOGNAME': 'cltbld', 10:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:57 INFO - 'MOZ_NO_REMOTE': '1', 10:54:57 INFO - 'NO_EM_RESTART': '1', 10:54:57 INFO - 'PAGER': '/bin/cat', 10:54:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:57 INFO - 'PWD': '/builds/slave/test', 10:54:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:57 INFO - 'SHELL': '/bin/bash', 10:54:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:54:57 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:54:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:57 INFO - 'USER': 'cltbld', 10:54:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:57 INFO - 'XPC_FLAGS': '0x0', 10:54:57 INFO - 'XPC_SERVICE_NAME': '0', 10:54:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:54:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:54:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:54:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:54:58 INFO - Installing collected packages: mozsystemmonitor 10:54:58 INFO - Running setup.py install for mozsystemmonitor 10:54:59 INFO - Successfully installed mozsystemmonitor 10:54:59 INFO - Cleaning up... 10:54:59 INFO - Return code: 0 10:54:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:54:59 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:54:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:54:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:54:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:54:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:54:59 INFO - 'HOME': '/Users/cltbld', 10:54:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:54:59 INFO - 'LOGNAME': 'cltbld', 10:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:54:59 INFO - 'MOZ_NO_REMOTE': '1', 10:54:59 INFO - 'NO_EM_RESTART': '1', 10:54:59 INFO - 'PAGER': '/bin/cat', 10:54:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:54:59 INFO - 'PWD': '/builds/slave/test', 10:54:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:54:59 INFO - 'SHELL': '/bin/bash', 10:54:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:54:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:54:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:54:59 INFO - 'USER': 'cltbld', 10:54:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:54:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:54:59 INFO - 'XPC_FLAGS': '0x0', 10:54:59 INFO - 'XPC_SERVICE_NAME': '0', 10:54:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:54:59 INFO - Downloading/unpacking jsonschema==2.5.1 10:54:59 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 10:54:59 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 10:54:59 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 10:54:59 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 10:55:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:55:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:55:00 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 10:55:00 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 10:55:00 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 10:55:00 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 10:55:00 INFO - Downloading functools32-3.2.3-2.tar.gz 10:55:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:55:01 INFO - warning: no files found matching '*.txt' 10:55:01 INFO - no previously-included directories found matching 'build' 10:55:01 INFO - no previously-included directories found matching 'dist' 10:55:01 INFO - no previously-included directories found matching '.git*' 10:55:01 INFO - Installing collected packages: jsonschema, functools32 10:55:01 INFO - Running setup.py install for functools32 10:55:01 INFO - warning: no files found matching '*.txt' 10:55:01 INFO - no previously-included directories found matching 'build' 10:55:01 INFO - no previously-included directories found matching 'dist' 10:55:01 INFO - no previously-included directories found matching '.git*' 10:55:01 INFO - Successfully installed jsonschema functools32 10:55:01 INFO - Cleaning up... 10:55:01 INFO - Return code: 0 10:55:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:02 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:02 INFO - 'HOME': '/Users/cltbld', 10:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:02 INFO - 'LOGNAME': 'cltbld', 10:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:02 INFO - 'MOZ_NO_REMOTE': '1', 10:55:02 INFO - 'NO_EM_RESTART': '1', 10:55:02 INFO - 'PAGER': '/bin/cat', 10:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:02 INFO - 'PWD': '/builds/slave/test', 10:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:02 INFO - 'SHELL': '/bin/bash', 10:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:02 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:02 INFO - 'USER': 'cltbld', 10:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:02 INFO - 'XPC_FLAGS': '0x0', 10:55:02 INFO - 'XPC_SERVICE_NAME': '0', 10:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:55:02 INFO - Cleaning up... 10:55:02 INFO - Return code: 0 10:55:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:03 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:55:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 10:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:55:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:03 INFO - 'HOME': '/Users/cltbld', 10:55:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:03 INFO - 'LOGNAME': 'cltbld', 10:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:03 INFO - 'MOZ_NO_REMOTE': '1', 10:55:03 INFO - 'NO_EM_RESTART': '1', 10:55:03 INFO - 'PAGER': '/bin/cat', 10:55:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:03 INFO - 'PWD': '/builds/slave/test', 10:55:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:03 INFO - 'SHELL': '/bin/bash', 10:55:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:03 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:03 INFO - 'USER': 'cltbld', 10:55:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:03 INFO - 'XPC_FLAGS': '0x0', 10:55:03 INFO - 'XPC_SERVICE_NAME': '0', 10:55:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:03 INFO - Downloading/unpacking blobuploader==1.2.4 10:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:55:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:55:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:06 INFO - Downloading docopt-0.6.1.tar.gz 10:55:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:55:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:55:06 INFO - Running setup.py install for blobuploader 10:55:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:55:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:55:06 INFO - Running setup.py install for requests 10:55:06 INFO - Running setup.py install for docopt 10:55:06 INFO - Successfully installed blobuploader requests docopt 10:55:06 INFO - Cleaning up... 10:55:07 INFO - Return code: 0 10:55:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:07 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:55:07 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:55:07 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:55:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:07 INFO - 'HOME': '/Users/cltbld', 10:55:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:07 INFO - 'LOGNAME': 'cltbld', 10:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:07 INFO - 'MOZ_NO_REMOTE': '1', 10:55:07 INFO - 'NO_EM_RESTART': '1', 10:55:07 INFO - 'PAGER': '/bin/cat', 10:55:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:07 INFO - 'PWD': '/builds/slave/test', 10:55:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:07 INFO - 'SHELL': '/bin/bash', 10:55:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:07 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:07 INFO - 'USER': 'cltbld', 10:55:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:07 INFO - 'XPC_FLAGS': '0x0', 10:55:07 INFO - 'XPC_SERVICE_NAME': '0', 10:55:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_g5HR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-YOR1D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-uu1KA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-C5_IRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xvsbrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vrVy8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hyPJWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ocV9xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-mjrpiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-XXHsmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-zVeSEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-exgyZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-5k1nZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fqJ0Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-DZHKGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7YNbBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-s5yKHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:55:09 INFO - Running setup.py install for manifestparser 10:55:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:55:09 INFO - Running setup.py install for mozcrash 10:55:09 INFO - Running setup.py install for mozdebug 10:55:09 INFO - Running setup.py install for mozdevice 10:55:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:55:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:55:09 INFO - Running setup.py install for mozfile 10:55:09 INFO - Running setup.py install for mozhttpd 10:55:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:55:09 INFO - Running setup.py install for mozinfo 10:55:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Running setup.py install for mozInstall 10:55:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Running setup.py install for mozleak 10:55:10 INFO - Running setup.py install for mozlog 10:55:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Running setup.py install for moznetwork 10:55:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:55:10 INFO - Running setup.py install for mozprocess 10:55:10 INFO - Running setup.py install for mozprofile 10:55:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:55:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:55:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:55:11 INFO - Running setup.py install for mozrunner 10:55:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:55:11 INFO - Running setup.py install for mozscreenshot 10:55:11 INFO - Running setup.py install for moztest 10:55:11 INFO - Running setup.py install for mozversion 10:55:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:55:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:55:11 INFO - Cleaning up... 10:55:11 INFO - Return code: 0 10:55:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:11 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:55:11 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:55:11 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:11 INFO - 'HOME': '/Users/cltbld', 10:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:11 INFO - 'LOGNAME': 'cltbld', 10:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:11 INFO - 'MOZ_NO_REMOTE': '1', 10:55:11 INFO - 'NO_EM_RESTART': '1', 10:55:11 INFO - 'PAGER': '/bin/cat', 10:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:11 INFO - 'PWD': '/builds/slave/test', 10:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:11 INFO - 'SHELL': '/bin/bash', 10:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:11 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:11 INFO - 'USER': 'cltbld', 10:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:11 INFO - 'XPC_FLAGS': '0x0', 10:55:11 INFO - 'XPC_SERVICE_NAME': '0', 10:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:11 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-kOIBJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3Ztm2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-rp0dSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wH8mOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-aDuurS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-I_kbVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-V77YvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LMeZKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hCV6cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:12 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)) 10:55:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:12 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-NzPvBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Vqbq_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:13 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)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fIeIat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-2lNFyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:13 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)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hqLg8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-EuMoml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:13 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)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-06yrGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:55:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:13 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-X3Aito-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:13 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)) 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:55:13 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)) 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:55:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:55:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:55:13 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 10:55:13 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 10:55:13 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 10:55:13 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 10:55:15 INFO - Downloading blessings-1.6.tar.gz 10:55:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:55:15 INFO - Installing collected packages: blessings 10:55:15 INFO - Running setup.py install for blessings 10:55:15 INFO - Successfully installed blessings 10:55:15 INFO - Cleaning up... 10:55:15 INFO - Return code: 0 10:55:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:55:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:55:15 INFO - Reading from file tmpfile_stdout 10:55:15 INFO - Current package versions: 10:55:15 INFO - blessings == 1.6 10:55:15 INFO - blobuploader == 1.2.4 10:55:15 INFO - docopt == 0.6.1 10:55:15 INFO - functools32 == 3.2.3-2 10:55:15 INFO - jsonschema == 2.5.1 10:55:15 INFO - manifestparser == 1.1 10:55:15 INFO - mozInstall == 1.12 10:55:15 INFO - mozcrash == 1.0 10:55:15 INFO - mozdebug == 0.1 10:55:15 INFO - mozdevice == 0.49 10:55:15 INFO - mozfile == 1.2 10:55:15 INFO - mozhttpd == 0.7 10:55:15 INFO - mozinfo == 0.9 10:55:15 INFO - mozleak == 0.1 10:55:15 INFO - mozlog == 3.4 10:55:15 INFO - moznetwork == 0.27 10:55:15 INFO - mozprocess == 0.25 10:55:15 INFO - mozprofile == 0.28 10:55:15 INFO - mozrunner == 6.13 10:55:15 INFO - mozscreenshot == 0.1 10:55:15 INFO - mozsystemmonitor == 0.3 10:55:15 INFO - moztest == 0.8 10:55:15 INFO - mozversion == 1.4 10:55:15 INFO - psutil == 3.1.1 10:55:15 INFO - requests == 1.2.3 10:55:15 INFO - wsgiref == 0.1.2 10:55:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:15 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:55:15 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:55:15 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:15 INFO - 'HOME': '/Users/cltbld', 10:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:15 INFO - 'LOGNAME': 'cltbld', 10:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:15 INFO - 'MOZ_NO_REMOTE': '1', 10:55:15 INFO - 'NO_EM_RESTART': '1', 10:55:15 INFO - 'PAGER': '/bin/cat', 10:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:15 INFO - 'PWD': '/builds/slave/test', 10:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:15 INFO - 'SHELL': '/bin/bash', 10:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:15 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:15 INFO - 'USER': 'cltbld', 10:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:15 INFO - 'XPC_FLAGS': '0x0', 10:55:15 INFO - 'XPC_SERVICE_NAME': '0', 10:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 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)) 10:55:16 INFO - Cleaning up... 10:55:16 INFO - Return code: 0 10:55:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:16 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/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 0x109ee5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ee8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd09579f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, '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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 10:55:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:55:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:16 INFO - 'HOME': '/Users/cltbld', 10:55:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:16 INFO - 'LOGNAME': 'cltbld', 10:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:16 INFO - 'MOZ_NO_REMOTE': '1', 10:55:16 INFO - 'NO_EM_RESTART': '1', 10:55:16 INFO - 'PAGER': '/bin/cat', 10:55:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:16 INFO - 'PWD': '/builds/slave/test', 10:55:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:16 INFO - 'SHELL': '/bin/bash', 10:55:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:16 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:16 INFO - 'USER': 'cltbld', 10:55:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:16 INFO - 'XPC_FLAGS': '0x0', 10:55:16 INFO - 'XPC_SERVICE_NAME': '0', 10:55:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:55:16 INFO - Cleaning up... 10:55:16 INFO - Return code: 0 10:55:16 INFO - Running post-action listener: _resource_record_post_action 10:55:16 INFO - Running post-action listener: _start_resource_monitoring 10:55:16 INFO - Starting resource monitoring. 10:55:16 INFO - [mozharness: 2017-05-30 17:55:16.320088Z] Finished create-virtualenv step (success) 10:55:16 INFO - [mozharness: 2017-05-30 17:55:16.321057Z] Running install step. 10:55:16 INFO - Running pre-action listener: _resource_record_pre_action 10:55:16 INFO - Running main action method: install 10:55:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:55:16 INFO - Reading from file tmpfile_stdout 10:55:16 INFO - Detecting whether we're running mozinstall >=1.0... 10:55:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:55:16 INFO - Reading from file tmpfile_stdout 10:55:16 INFO - Output received: 10:55:16 INFO - Usage: mozinstall [options] installer 10:55:16 INFO - Options: 10:55:16 INFO - -h, --help show this help message and exit 10:55:16 INFO - -d DEST, --destination=DEST 10:55:16 INFO - Directory to install application into. [default: 10:55:16 INFO - "/builds/slave/test"] 10:55:16 INFO - --app=APP Application being installed. [default: firefox] 10:55:16 INFO - mkdir: /builds/slave/test/build/application 10:55:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:55:31 INFO - Reading from file tmpfile_stdout 10:55:31 INFO - Output received: 10:55:31 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:55:31 INFO - Running post-action listener: _resource_record_post_action 10:55:31 INFO - [mozharness: 2017-05-30 17:55:31.615891Z] Finished install step (success) 10:55:31 INFO - [mozharness: 2017-05-30 17:55:31.616035Z] Running run-tests step. 10:55:31 INFO - Running pre-action listener: _resource_record_pre_action 10:55:31 INFO - Running main action method: run_tests 10:55:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:55:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:55:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:55:31 INFO - 2017-05-30 10:55:31.630 screenresolution[2415:33735] starting screenresolution argv=screenresolution get 10:55:31 INFO - 2017-05-30 10:55:31.633 screenresolution[2415:33735] Display 0: 1600x1200x32@60 10:55:31 INFO - 2017-05-30 10:55:31.639 screenresolution[2416:33737] starting screenresolution argv=screenresolution list 10:55:31 INFO - Available Modes on Display 0 10:55:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:55:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:55:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:55:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:55:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:55:31 INFO - Intel Iris: 10:55:31 INFO - Chipset Model: Intel Iris 10:55:31 INFO - Type: GPU 10:55:31 INFO - Bus: Built-In 10:55:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:55:31 INFO - Vendor: Intel (0x8086) 10:55:31 INFO - Device ID: 0x0a2e 10:55:31 INFO - Revision ID: 0x0009 10:55:31 INFO - Displays: 10:55:31 INFO - Display: 10:55:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:55:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:55:31 INFO - Display Serial Number: 1600x1200 60 10:55:31 INFO - Main Display: Yes 10:55:31 INFO - Mirror: Off 10:55:31 INFO - Online: Yes 10:55:31 INFO - Rotation: Supported 10:55:31 INFO - Return code: 0 10:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:55:31 INFO - Python 2.7.3 10:55:31 INFO - Return code: 0 10:55:31 INFO - grabbing minidump binary from tooltool 10:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 10:55:31 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 0x109cc3d30>, '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 0x109beadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cb8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:55:31 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 10:55:31 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 10:55:31 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 10:55:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:55:32 INFO - Return code: 0 10:55:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:55:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:55:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:55:32 INFO - ENV: RUST_BACKTRACE is now 1 10:55:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:55:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:55:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 10:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 10:55:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8GIyydsL5Z/Render', 10:55:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:32 INFO - 'HOME': '/Users/cltbld', 10:55:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:32 INFO - 'LOGNAME': 'cltbld', 10:55:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:55:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:32 INFO - 'MOZ_NO_REMOTE': '1', 10:55:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:55:32 INFO - 'NO_EM_RESTART': '1', 10:55:32 INFO - 'PAGER': '/bin/cat', 10:55:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:32 INFO - 'PWD': '/builds/slave/test', 10:55:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:55:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:32 INFO - 'RUST_BACKTRACE': '1', 10:55:32 INFO - 'SHELL': '/bin/bash', 10:55:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZujwO4gHCJ/Listeners', 10:55:32 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 10:55:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:32 INFO - 'USER': 'cltbld', 10:55:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:32 INFO - 'XPC_FLAGS': '0x0', 10:55:32 INFO - 'XPC_SERVICE_NAME': '0', 10:55:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 10:55:32 INFO - mozversion application_buildid: 20170530100116 10:55:32 INFO - mozversion application_changeset: 1bf2d9f8a5cb68370f9a797890beb14769f4144b 10:55:32 INFO - mozversion application_display_name: Firefox 10:55:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:55:32 INFO - mozversion application_name: Firefox 10:55:32 INFO - mozversion application_remotingname: firefox 10:55:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:55:32 INFO - mozversion application_vendor: Mozilla 10:55:32 INFO - mozversion application_version: 54.0 10:55:32 INFO - mozversion platform_buildid: 20170530100116 10:55:32 INFO - mozversion platform_changeset: 1bf2d9f8a5cb68370f9a797890beb14769f4144b 10:55:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:55:32 INFO - mozversion platform_version: 54.0 10:55:32 INFO - using testdate: 1496166932 10:55:32 INFO - actual date: 1496166932 10:55:32 INFO - starting webserver on 'localhost:58475' 10:55:32 INFO - SUITE-START | Running 2 tests 10:55:32 INFO - TEST-START | dromaeo_css 10:55:32 INFO - operating with platform_type : mac_ 10:55:32 INFO - Initialising browser for dromaeo_css test... 10:55:32 INFO - TEST-INFO | started process 2426 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp81PSEx/profile http://localhost:58475/getInfo.html) 10:55:34 INFO - PROCESS | 2426 | __metrics Screen width/height:1600/1200 10:55:34 INFO - PROCESS | 2426 | colorDepth:24 10:55:34 INFO - PROCESS | 2426 | Browser inner width/height: 1024/689 10:55:34 INFO - PROCESS | 2426 | __metrics 10:55:34 INFO - PROCESS | 2426 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:55:34 INFO - PROCESS | 2426 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:55:35 INFO - TEST-INFO | 2426: exit 0 10:55:35 INFO - Browser initialized. 10:55:35 INFO - Running cycle 1/1 for dromaeo_css test... 10:55:35 INFO - TEST-INFO | started process 2429 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp81PSEx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:55:47 INFO - PROCESS | 2429 | RSS: Main: 165076992 10:55:47 INFO - PROCESS | 2429 | 10:58:52 INFO - PROCESS | 2429 | Cycle 1(1): loaded http://localhost:58475/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58475/tests/dromaeo/cssquery-ext.html) 10:58:53 INFO - PROCESS | 2429 | RSS: Main: 284827648 10:58:53 INFO - PROCESS | 2429 | 11:01:56 INFO - PROCESS | 2429 | Cycle 1(1): loaded http://localhost:58475/tests/dromaeo/cssquery-ext.html (next: http://localhost:58475/tests/dromaeo/cssquery-jquery.html) 11:01:56 INFO - PROCESS | 2429 | RSS: Main: 302555136 11:01:56 INFO - PROCESS | 2429 | 11:04:59 INFO - PROCESS | 2429 | Cycle 1(1): loaded http://localhost:58475/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58475/tests/dromaeo/cssquery-mootools.html) 11:04:59 INFO - PROCESS | 2429 | RSS: Main: 278245376 11:04:59 INFO - PROCESS | 2429 | 11:08:02 INFO - PROCESS | 2429 | Cycle 1(1): loaded http://localhost:58475/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58475/tests/dromaeo/cssquery-prototype.html) 11:08:02 INFO - PROCESS | 2429 | RSS: Main: 299368448 11:08:02 INFO - PROCESS | 2429 | 11:11:06 INFO - PROCESS | 2429 | Cycle 1(1): loaded http://localhost:58475/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58475/tests/dromaeo/cssquery-yui.html) 11:11:07 INFO - PROCESS | 2429 | RSS: Main: 267223040 11:11:07 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | Cycle 1(1): loaded http://localhost:58475/tests/dromaeo/cssquery-yui.html (next: http://localhost:58475/tests/dromaeo/cssquery-dojo.html) 11:14:11 INFO - PROCESS | 2429 | RSS: Main: 478871552 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | __start_tp_report 11:14:11 INFO - PROCESS | 2429 | _x_x_mozilla_page_load 11:14:11 INFO - PROCESS | 2429 | _x_x_mozilla_page_load_details 11:14:11 INFO - PROCESS | 2429 | |i|pagename|runs| 11:14:11 INFO - PROCESS | 2429 | |0;dojo.html;3076.923076923077;3511.245082307304;3515.8066306353157;3532.2582257725876;3581.015220814276;6013.969930150349;6033;6082.939170608292;6098.871708733887;6108.053251745981;6153.446189842914;6172.783952561661;6178.25860896693;6189.907151392733;6202.9689851550675;5418.539424148962;5430.8913821723545;5489.560835133199;5517.724113794295;5608.186812912125;5346.171343441753;5376.247325374431;5418.91871621924;5600.887982240352;5606.103023516238;5466.863328416802;5474.260974768415;5475.72621368932;5478.178273259023;5488.423715509874;5471.206675032098;5477.068898287301;5477.7261136943;5479.972600136994;5481.890362192754;5294.100002999499;5393.379761327458;5403.081476149025;5420.864478388072;5443.59173062018;6106.339326077114;6109.2528441424865;6117.675163990739;6157.538184636171;6213.409726076061;6433.228012638467;6449.12936753537;6453.903191452132;6456.128422662928;6462.838429039265;6543.541952063358;6580.4406625436895;6583.9012414813815;6592;6594.53838231324;5732.684702341369;6107.591005093845;6132.049587189136;6157.168782214344;6163.599366041193;6700.363465470772;6719.731210751613;6733;6739.629320387426;6746.291639377795;6383.042543618494;6387.073874288202;6391.296957334689;6392.616443013434;6396.264429590564;5554.472325129187;6220.906686399708;6284.560080794392;6494.285628580852;6550.606963582105;6360.52296077796;6393.264774550893;6494.285628580852;6495.1556297681;6495.1556297681;6472.838179045561;6485.383888530582;6486.416222539994;6499.837504062436;6500.70746816385;5131.153359695609;5203.7918483261;5297.364316282071;5334.359876814807;5351.170568561879;4662.370579971728;4681.157391669532;4685.390899183084;4688.367070445514;4695.366125573004;6143.109249158847;6164.445199932027;6176.3514830943;6177.135201071854;6193.194884664965;6570.113034740249;6571.145751052332;6579.013148027834;6579.736810527621;6584.670766461658;6185;6256.249250090018;6258.780942666985;6260.154879091356;6289.842753931189;5594.9720251398485;5626.859328516738;5691.459311365414;5732.971335143381;5744.281964754406;6752.561083529555;6772.0857684212;6777.762778302736;6785.321467853175;6791.762288320083;6529.7061632227615;6545.901811472927;6559.344065593594;6560.278369379269;6565.80302590923;6458.257300410513;6468.256150542559;6722;6756.797296081126;6773.830654233683;6435.356464353527;6438.967805161132;6472.546921715435;6477.255115661759;6492.31830657793;6515.087887695631;6528.183977002875;6543.149390579194;6561.671916404065;6573.1454910861285;6174.722137503733;6247.0629405589525;6293.748250069946;6388.297287298301;6396.072569477306;6230.813075607739;6301;6335.619862808247;6358.237011558643;6383.0744542042075;6108.342071131425;6269.96865015672;6273;6284.874302514016;6288.276848162337;6203.906941395792;6215.875682486416;6253.906191407224;6282.371762823681;6391.520635952229;6144.801763656044;6296.244450665949;6297.52768542352;6306.400891915261;6315.810525684236 11:14:11 INFO - PROCESS | 2429 | |1;ext.html;799.4843326054695;805.7784109369923;805.7824387415397;894.2622336572325;898.4519443139679;28600.856995715018;28911.13266602003;30370.240743981383;39292.82121536358;39650.80174599123;41284.38073428901;41446.79276603613;41461.37807932883;41475.96310092242;41509.37735933963;9573.042695730424;9739.366941148837;9747.658831940884;9752.609895604202;9765.95117024414;7999.600019998977;8159.796005099861;8207.753767386988;11667.299962002264;11823.881761182407;11944.880551194507;11948.044156467462;11970.521179152824;11995.98034167097;12004.399780010965;11893.108016898725;11903.28580285177;11932.880671193263;11940.820887686605;11961.999999999956;11558.364289964049;11614.593489227836;11638;11648;11649.534018639244;39845.804625861274;40006;40039;40085;40132.79933600342;4015.477987861559;4051.250518654029;4067.4508941292843;4086.5504794472586;4096.283150448659;28295.717042829514;28562.428751425065;29395.570078795245;29940.251493712833;30656.08031759051;3249.3988612106837;3257.250832308582;3284.26104126572;3419.504171895086;3424.075499615115;349266;351516.99999999744;352696;352860.47139528533;354337;52481;52654;52674.683132921986;52690;52799;54912.725436372566;54916.45083549113;54943;55036;55112.44887551153;15054.849451505344;15416.383344666088;15424.614384640474;15434.768478472832;15461.922690386476;12958.546450874173;13206.405711742802;13213.603591892259;13240.271785051915;13240.801387978996;10451.111655509214;10644.414557199274;10798.974097460728;11759.05927525796;12022.278663280056;5198.116320225571;5255.947440525622;5259.395884255262;5260.184671375943;5263.763130659129;1092.4210168610648;1114.793763153819;1133.4389477208833;1135.6195674448986;1189.1319336884123;23894.044238230625;25132.74867251304;26833.926642934453;26884;27921.581176282372;1059.5073290919759;1088.9945550272198;1100.8073587122383;1121.1255220927526;1136.4545018391218;13187.868121318665;13560.301495020836;13808.585742427744;13840;13910.443582256596;487730;487798;487939.5603022033;488873;489591.5520422375;40259.79220623327;45414.54585454103;45434.54565454369;45761.31358029632;45801.77099114483;1441.978370324446;1457.5918742752046;1488.1889370293216;1495.8055452791284;1496.4089184771738;22466.101355946234;24744.25767226986;26977.595336070368;27382.45235095247;27594.862025690545;35816.46275305925;36021.09947251339;36052.4592131113;36081.639183607826;36099.819500902326;422771;424639;424807;425162;425619;28641;37525.37322387406;38358.6164138355;38421.61578384292;38471;31434.842825785723;35202.823985879906;42921.570784293006;47798.5220147808;50028.749856250484;32188.356232874743;32194.19514512156;32320.676793231767;32420.513692295106;33413.83293083519;31862.840685796422;32868.50697239591;33099.50350744789;33133.502997454576;33258.168545786546;38491.65279215214;39420.211595768495;39542.40686389649;39678.801605991786;39764.2047159061;32551.67448325581;33266.334673305915;33378.99863004114;33767.3246535063;34638;25917.740822592288;26202.606960895984;26249.475010500064;26385.51094453917;26719.465610688065 11:14:11 INFO - PROCESS | 2429 | |2;jquery.html;362.2609875853259;418.93087640539295;426.188111647312;433.66824379349805;463.6290967226218;19226.519337016565;20455.488612784688;21594;21615.81113038782;21776.237831675895;21319;21963.890180549075;22140.22509212178;22460.775392246072;22999;6811.557248778813;7129.5365801223015;7145.821354466154;7152.678129484159;7159.212486626467;6806.353396427331;6956.130483689539;6959.652017399135;6964.582125072487;7019.262977387378;6258.812235632939;6921.792346229621;6930.376266136047;6942.652867356637;7534.849303013936;7702.614869256515;7854.7250846220395;7928.929594504754;7937.047554293522;7942.292407132465;7663.233676632348;7761.767146985543;7762.107357653829;7771.184025677336;7775.494592851446;21706.457338566503;24578.262652120287;26674.866625666746;26816.463670726673;27052.729472705218;2857.414230082849;2974.4943359628915;2984.2688541307302;3005.113491520007;3020.0939718084487;15423;16097.758533622005;16358.591035224095;16391.852570320083;16660.75008874868;2036.3381900882273;2050.507878109258;2058.6809044597962;2065.607534568442;2117.8305735541276;127119.36440317739;144765;165263;176473.11763441228;178740;16329.10189939541;18998.71501927472;19011.904940475484;19132.330368437037;20078.297259595845;31179.53230701541;31199.532007019912;31236.375272494424;31446.05661830149;31495.212619684233;8564.914350856536;10215.336003159771;10453.895461045444;10788;10801.83797243042;8821.294296456268;8979.640814367353;8983.326250531236;9171.220446262143;9194.63221471147;8147.837043259102;9050.818983620422;9360.953195234117;9391.389559678599;9410.25910206614;3562.1628917204434;4004.8998775030273;4030.919381612351;4085.754854708727;4091.202215567995;549.5383877542884;809.6923169195668;858.6729961803458;883.960221790021;1033.3851358441716;22011.950200835483;22179.223727169796;22290.331290061324;22339.994700238527;22372.664410033864;856.2678909532385;865.4158443050916;875.5272153037416;887.2946007923716;890.0387581412059;11245.493952772145;11497.252678575866;11716.9454749073;11763.411829408495;11772.470238839272;803006;803038;804728;814252;825508;139774;154293;159378;162961;166505;1254.717688520094;1268.632096691949;1320.854705982341;1321.8678132186897;1325.2578556008666;19385.8061419384;19535.902320488305;20126.39620811378;20221.494462637962;20274.695879561525;61808.69095654493;63097.684511578984;63385.36614633795;64115;64162.39594010187;91860.54069729875;92812;94511;95810.12569622922;96148.03851961391;10334.948325258325;11839;11857.822132668189;12088.879111208775;12237.877621223673;13254.337283135997;13361.866381336062;13418.798718019432;13458.52895148665;13857.237851918242;9586.568604412678;11275;11553.942230288794;11812.704682382666;11895.762084758084;9229.584668689791;10785.676429707122;10833.187510936868;10907.181961352771;11139.10887129044;11614.187006909435;11670.474828632561;11719.472623731783;11946.820797688215;11958.880411195776;11679.708007299885;11685.76628467443;12001.879981200076;12013.318135460868;12023.63929082129;9599;11303.7739245213;11335.886641133813;11620.418979050844;11875.287482751062 11:14:11 INFO - PROCESS | 2429 | |3;mootools.html;1418.524794193945;1580.5574439157026;1597.624558228816;1637.6560922206336;1649.7030534503788;10778;11620.128490363215;11622.302661840295;11652.592159274427;11663.06695464363;10820.891791082087;11108.833367499474;11360.318380897132;11462.31226126433;11503;2498.950020999579;2529.6964364276314;2532.0378256262593;2538.9619155712585;2541.961870571943;2482.7517248275117;2484.925452236436;2533.050106210171;2534.1510593951048;2542.4660821227562;2444.511097780442;2502.9749702503013;2505.6116301973134;2524.772770450659;2532.278300684305;2443.7434069422634;2501.524710305045;2515.2579988903226;2515.9119430820015;2525.7497538718453;2481.801455883525;2505.2985164154065;2511.208969174704;2517.9496410071697;2525.9242222733346;11192.552297908074;11442.771144577062;11466.426678666117;11473.254238474556;11475.852414758541;5873.882522349571;6009.909851352233;6041.365656606037;6047.213862197887;6050.667213303266;3599.4240921452442;3712.053426376257;3771.30230907283;3796.4495148203355;3844.7116466265143;1004.7889943111956;1149.752803147319;1247.5384107880143;1257.1953949472345;1316.9012324075636;14098.224597647126;14340.713185736331;14756.409743610242;14856.33146508399;16141.273642686107;4770.928436073462;4898.951010489921;4911.18965370717;4949.3667089860255;4968.701877887338;10361.222908281909;12104.152709310263;12105;12105.394730263451;12116;5146.356705411824;5191.273221748958;5196.740162991835;5197.766100525486;5199.896002080013;5353.544948679406;5400.648957817729;5489.945100548944;5521.282233309644;5539.335279766373;3844.5770965194365;3885.0870045539277;3921.764694118362;3954.426608141803;3965.5439624443557;924.9907500924904;1074.60776816463;1075.1560025380195;1077.138289368502;1092.9016388525072;844.2697067036969;995.8107959487675;1005.2410430125209;1022.4887556221889;1035.8705161854768;6512.218533775977;6568.770093046721;6586.901196482057;6866.759663411856;6872.381485666314;973.7711637765119;997.8403455447094;1015.3248090020202;1027.1474676018886;1033.8552602635637;4058.289799285113;4243.236217480821;4388.188185185687;4392.978035109868;4519.525449827786;13349;13578.45686172542;14172.149671019772;14173.291335433187;14312.926530510253;13849.792253116211;13866.791998120036;14198.432062717375;14217;14220.786688199685;10090;10407.479626018669;10529.42088185157;10532;10564.577416903237;6638.402543771083;6921.7231310747;6925.272846351262;6945.236024037453;7109.395701365235;12452.128351015343;12474.688132796753;12593.370331483573;12613.558525451564;12690.492380304684;12800.16798908068;12861;12932.547360842325;12943.611691649265;13083.672908176992;9382.765430864283;9429.905700942903;9441.952790236279;9505.904940950779;9544.284178686488;10402.47987600632;10427.21795865298;10504.78990420172;10533.631322903662;10585.788284234426;8951.462912225288;8974.282057435521;9001.54992250398;9033.954830225806;9135.132162444557;8631.913680863363;8747.212750852454;8790.34072444582;8819.823603528022;8834.823303533765;8019.315943651898;8040.437169398086;8089.110197878111;8100.149484304063;8142.592870356577;5835.44563266489;5917.6745279010065;5947.791827286024;6073.544484163794;6102.603330783485;5788.421157884178;5940.049592065248;5960.165576819336;5970.104484327386;6115.908261376172 11:14:11 INFO - PROCESS | 2429 | |4;prototype.html;354.2348527181287;354.5957608326508;379.2699054320433;385.9771605246096;388.986385476508;4890.779914903829;4977.402711674604;5198.168293073109;5198.478737812163;5271;4354.542773008836;4374.278244089729;4751.287306903966;4980.950190498094;4994.777324090781;3879.515060617423;4276.615104640589;4368.1918845013615;4384.057427653054;4409.757463339515;3583.784972901621;4151.130630431479;4357.978210108945;4384.583464570861;4441.711288766236;3924.3917192835015;3944.8224829882724;4155.029154154794;4311.779433591702;4354.7604881731495;3515.9472607910902;3786.602406747293;4289.871303860889;4289.978550107261;4344.044310251739;3720.7395482316247;4048.919021619551;4092.161106973093;4129.359949207877;4164.1879833432495;4575.702579332333;4882.6826256293225;4895.706257624518;4926.187179115477;4928.556429921288;4141.834326626931;4143.7720925349095;4257.382679511454;4751.3585665935;5047.217681259411;4463.285874260103;4675.298705194214;4858.246971719389;5486.286782718221;5504.926539324833;4743.335932969387;4745.762711864428;4762.380890484214;4821.710697358169;4828.517148285178;4703.212779143903;4706.352776528222;5250.343707036659;5687.232223649754;5722.799702010451;3961.7821019844187;4300.849470268526;4606.700564463299;4784.378030855966;4862.586680132228;4269.958009416703;4532.32015197723;4856.39295088114;4914.778834952435;4929.968069637147;4254.617084462413;4812.25410061441;4828.493008234085;5090.974545127251;5109.266542399799;4436;4993.500649934977;5394.757235924393;5422.48486393792;5441.6190866639745;1515.9772603410959;1553.3320672111101;1564.6635973265802;1583.279607778458;1634.6812371587425;1191.928484290928;1334.8531661517225;1342.89256859451;1355.8204362204747;1404.9719005619831;4586.83035825869;4672.159011377985;4878.07316609843;5120.462351453044;5195.948040519546;4547.47704014036;4602.654800889946;4991.675541089818;5009;5404.1904952176565;4519.796609152596;4695.43654761424;4808.8797780055775;4944.8763780905765;5237.895242095136;2292.747797742247;2410.6745589345564;2855.215490318551;2878.8416637084742;2899.144752298077;5110.2590124431745;5362.678239305654;5677.344577446454;5682.062459694187;5805.126462401138;5218.9739051304505;5239.87784947097;5489.325755644567;5762.798302059407;5779.479846813807;5665.6204394155375;5787.932395830217;5812.534997200214;5813.738381772746;5833.329167145747;4778.423120370339;4969.552740253394;5237.528622424;5428.45715428454;5567.8864227155545;4451.799669014967;5116.6418350716585;5166.689998600096;5327;5518.427248234234;5730.856728581819;5739.483446489836;5742.511886489695;5771.653700777967;5804.562083924678;4035.717499775105;4216.49402071741;4408.228560002051;4481.619062379734;4533.886652833706;4857.489963553776;4910.361652985089;5244.5279924806955;5535.557155427637;5619.8809660638735;5064.898702026013;5305.336832895888;5316.627836051594;5420.701861397661;5422.972885135549;4614.86935700742;4786.978937692216;5140.871478213075;5450.5367043801725;5473.945260547503;4923.803047878045;4974.602031837372;5052.317937078447;5294.788208471618;5315.963387139471;5374.865628359166;5388.168022872243;5451.37309209446;5455.618106732491;5459.426760190282;4722.976385118052;5102.591792656504;5371.743867575706;5373.361124856956;5373.489518495893 11:14:11 INFO - PROCESS | 2429 | |5;yui.html;754.1289810269141;784.4587234807982;819.397742659145;858.8454078265912;880.6917578847405;14860.702785944275;17229.96620202789;17272.309107635694;17272.913635431836;17289.308427662894;15782;15872.12703301318;15988.680226395465;16009.919950400232;16065.196740162974;984.3601658921705;1001.6794625719773;1007.4308015970989;1008.6721815409984;1031.5667419683714;901.8951784064501;977.4623956823754;1036.3678156324675;1036.4972988100765;1036.5646428500047;1079.3362082319356;1080.0387654987028;1080.2654195147297;1081.4754843900703;1082.2802836114015;1070.550368845087;1071.2822408985999;1073.5866691323818;1073.720832583526;1073.9731506712317;798.66456088443;798.9800254993613;799.2367289238763;799.9240072193132;800.3677095094868;15359;15379.461718839786;15560.92219538895;16818.83181168185;16829;14574.344154513072;14650.853491465163;14790.630234244123;14838.777418338734;14840.332185051699;10223.335483193643;11753.294802311888;12050.638480845588;12087.697807554794;12649.557265495663;3889.7082718796205;3907.882763517099;3929.1945151244213;4020.4974378202724;4059.269331520325;111875;113788;118007.99999999914;118223.2266516003;120307;10921.890781092088;10934.726631834268;11487.71280717972;11597.594084207181;12017.459214335377;24240.636390454158;24255.636165457534;24500.632490512657;24591.877040614683;24680.382990425383;8030.43786934909;8062.959685201537;8070.192980701883;8194.795130121676;8357.665693372197;4577.5880170784785;4580.748058856728;4608.170529304691;4643.442786865598;4760.571548560646;3589.8205089745406;3669.7064234861145;3681.502997095358;3690.612485688964;3707.3141468828353;597.8475488750341;611.1169360274449;612.6752821004875;613.9447449729546;716.6022857314241;679.7315060551055;681.1519658025691;716.2014353995362;726.5241266970143;740.1784019738086;10404.97362098435;10696.83954740664;10987.340759554452;11193.832092518618;11282.661520154408;718.2888939949412;719.9496035277481;721.9566825990457;722.2344315026089;739.3198257603018;4232.470941132358;5231.215317702299;5232.476752324737;5252.737363131828;5254.527092561688;84849.15150848536;89434.55282723544;94187;97559;99245;50650.120229390275;58269.70865145732;63703;66337;84363.5781821087;55584.16623750647;67550.98673519901;70623;71571.64214178895;79553.40893182105;9196.448213107235;11034.393108379116;11204.83192752093;11483.94258028738;11595.536178552764;44991.77504112459;48519.787005323735;48555.843870148325;48666.29341544854;49424.50575494199;58500.829983400945;62834.685826570574;69493;72263.63868180626;73605.6319718398;12608.558700445441;13936.163830170222;14030.719385612434;14680.41278348854;14927.776083358542;14851.93848604;14941.626459338604;15896.205189740236;16509;16517.834821651628;14408.20754858493;14561.106505086496;14705.41178352854;14716.19060951658;15087.245637717851;14346.21095839697;14840.183789891227;14998.625034374229;15347.462838800588;15607.531774046796;13290.335483225994;14442.638934026312;14444.133351998913;14446.988710790143;14914.758598738716;10009.749756256151;10530.78938421212;10641.308314959502;10645.61123841773;10744.140468762636;10878.12974962017;11666.046765354888;11848.76302473963;12313.906635871745;12334.198277111958 11:14:11 INFO - PROCESS | 2429 | __end_tp_report 11:14:11 INFO - PROCESS | 2429 | __start_cc_report 11:14:11 INFO - PROCESS | 2429 | _x_x_mozilla_cycle_collect,1189 11:14:11 INFO - PROCESS | 2429 | __end_cc_report 11:14:11 INFO - PROCESS | 2429 | __startTimestamp1496168051287__endTimestamp 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | ------- Summary: start ------- 11:14:11 INFO - PROCESS | 2429 | Number of tests: 6 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | [#0] dojo.html Cycles:165 Average:6045.60 Median:6258.78 stddev:676.08 (10.8%) stddev-sans-first:636.78 11:14:11 INFO - PROCESS | 2429 | Values: 3076.9 3511.2 3515.8 3532.3 3581.0 6014.0 6033.0 6082.9 6098.9 6108.1 6153.4 6172.8 6178.3 6189.9 6203.0 5418.5 5430.9 5489.6 5517.7 5608.2 5346.2 5376.2 5418.9 5600.9 5606.1 5466.9 5474.3 5475.7 5478.2 5488.4 5471.2 5477.1 5477.7 5480.0 5481.9 5294.1 5393.4 5403.1 5420.9 5443.6 6106.3 6109.3 6117.7 6157.5 6213.4 6433.2 6449.1 6453.9 6456.1 6462.8 6543.5 6580.4 6583.9 6592.0 6594.5 5732.7 6107.6 6132.0 6157.2 6163.6 6700.4 6719.7 6733.0 6739.6 6746.3 6383.0 6387.1 6391.3 6392.6 6396.3 5554.5 6220.9 6284.6 6494.3 6550.6 6360.5 6393.3 6494.3 6495.2 6495.2 6472.8 6485.4 6486.4 6499.8 6500.7 5131.2 5203.8 5297.4 5334.4 5351.2 4662.4 4681.2 4685.4 4688.4 4695.4 6143.1 6164.4 6176.4 6177.1 6193.2 6570.1 6571.1 6579.0 6579.7 6584.7 6185.0 6256.2 6258.8 6260.2 6289.8 5595.0 5626.9 5691.5 5733.0 5744.3 6752.6 6772.1 6777.8 6785.3 6791.8 6529.7 6545.9 6559.3 6560.3 6565.8 6458.3 6468.3 6722.0 6756.8 6773.8 6435.4 6439.0 6472.5 6477.3 6492.3 6515.1 6528.2 6543.1 6561.7 6573.1 6174.7 6247.1 6293.7 6388.3 6396.1 6230.8 6301.0 6335.6 6358.2 6383.1 6108.3 6270.0 6273.0 6284.9 6288.3 6203.9 6215.9 6253.9 6282.4 6391.5 6144.8 6296.2 6297.5 6306.4 6315.8 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | [#1] ext.html Cycles:180 Average:56030.69 Median:33322.67 stddev:112803.77 (338.5%) stddev-sans-first:113044.00 11:14:11 INFO - PROCESS | 2429 | Values: 799.5 805.8 805.8 894.3 898.5 28600.9 28911.1 30370.2 39292.8 39650.8 41284.4 41446.8 41461.4 41476.0 41509.4 9573.0 9739.4 9747.7 9752.6 9766.0 7999.6 8159.8 8207.8 11667.3 11823.9 11944.9 11948.0 11970.5 11996.0 12004.4 11893.1 11903.3 11932.9 11940.8 11962.0 11558.4 11614.6 11638.0 11648.0 11649.5 39845.8 40006.0 40039.0 40085.0 40132.8 4015.5 4051.3 4067.5 4086.6 4096.3 28295.7 28562.4 29395.6 29940.3 30656.1 3249.4 3257.3 3284.3 3419.5 3424.1 349266.0 351517.0 352696.0 352860.5 354337.0 52481.0 52654.0 52674.7 52690.0 52799.0 54912.7 54916.5 54943.0 55036.0 55112.4 15054.8 15416.4 15424.6 15434.8 15461.9 12958.5 13206.4 13213.6 13240.3 13240.8 10451.1 10644.4 10799.0 11759.1 12022.3 5198.1 5255.9 5259.4 5260.2 5263.8 1092.4 1114.8 1133.4 1135.6 1189.1 23894.0 25132.7 26833.9 26884.0 27921.6 1059.5 1089.0 1100.8 1121.1 1136.5 13187.9 13560.3 13808.6 13840.0 13910.4 487730.0 487798.0 487939.6 488873.0 489591.6 40259.8 45414.5 45434.5 45761.3 45801.8 1442.0 1457.6 1488.2 1495.8 1496.4 22466.1 24744.3 26977.6 27382.5 27594.9 35816.5 36021.1 36052.5 36081.6 36099.8 422771.0 424639.0 424807.0 425162.0 425619.0 28641.0 37525.4 38358.6 38421.6 38471.0 31434.8 35202.8 42921.6 47798.5 50028.7 32188.4 32194.2 32320.7 32420.5 33413.8 31862.8 32868.5 33099.5 33133.5 33258.2 38491.7 39420.2 39542.4 39678.8 39764.2 32551.7 33266.3 33379.0 33767.3 34638.0 25917.7 26202.6 26249.5 26385.5 26719.5 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | [#2] jquery.html Cycles:180 Average:45281.24 Median:22416.72 stddev:134876.77 (601.7%) stddev-sans-first:135212.96 11:14:11 INFO - PROCESS | 2429 | Values: 362.3 418.9 426.2 433.7 463.6 19226.5 20455.5 21594.0 21615.8 21776.2 21319.0 21963.9 22140.2 22460.8 22999.0 6811.6 7129.5 7145.8 7152.7 7159.2 6806.4 6956.1 6959.7 6964.6 7019.3 6258.8 6921.8 6930.4 6942.7 7534.8 7702.6 7854.7 7928.9 7937.0 7942.3 7663.2 7761.8 7762.1 7771.2 7775.5 21706.5 24578.3 26674.9 26816.5 27052.7 2857.4 2974.5 2984.3 3005.1 3020.1 15423.0 16097.8 16358.6 16391.9 16660.8 2036.3 2050.5 2058.7 2065.6 2117.8 127119.4 144765.0 165263.0 176473.1 178740.0 16329.1 18998.7 19011.9 19132.3 20078.3 31179.5 31199.5 31236.4 31446.1 31495.2 8564.9 10215.3 10453.9 10788.0 10801.8 8821.3 8979.6 8983.3 9171.2 9194.6 8147.8 9050.8 9361.0 9391.4 9410.3 3562.2 4004.9 4030.9 4085.8 4091.2 549.5 809.7 858.7 884.0 1033.4 22012.0 22179.2 22290.3 22340.0 22372.7 856.3 865.4 875.5 887.3 890.0 11245.5 11497.3 11716.9 11763.4 11772.5 803006.0 803038.0 804728.0 814252.0 825508.0 139774.0 154293.0 159378.0 162961.0 166505.0 1254.7 1268.6 1320.9 1321.9 1325.3 19385.8 19535.9 20126.4 20221.5 20274.7 61808.7 63097.7 63385.4 64115.0 64162.4 91860.5 92812.0 94511.0 95810.1 96148.0 10334.9 11839.0 11857.8 12088.9 12237.9 13254.3 13361.9 13418.8 13458.5 13857.2 9586.6 11275.0 11553.9 11812.7 11895.8 9229.6 10785.7 10833.2 10907.2 11139.1 11614.2 11670.5 11719.5 11946.8 11958.9 11679.7 11685.8 12001.9 12013.3 12023.6 9599.0 11303.8 11335.9 11620.4 11875.3 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | [#3] mootools.html Cycles:180 Average:6884.95 Median:2525.84 stddev:4283.58 (169.6%) stddev-sans-first:4275.90 11:14:11 INFO - PROCESS | 2429 | Values: 1418.5 1580.6 1597.6 1637.7 1649.7 10778.0 11620.1 11622.3 11652.6 11663.1 10820.9 11108.8 11360.3 11462.3 11503.0 2499.0 2529.7 2532.0 2539.0 2542.0 2482.8 2484.9 2533.1 2534.2 2542.5 2444.5 2503.0 2505.6 2524.8 2532.3 2443.7 2501.5 2515.3 2515.9 2525.7 2481.8 2505.3 2511.2 2517.9 2525.9 11192.6 11442.8 11466.4 11473.3 11475.9 5873.9 6009.9 6041.4 6047.2 6050.7 3599.4 3712.1 3771.3 3796.4 3844.7 1004.8 1149.8 1247.5 1257.2 1316.9 14098.2 14340.7 14756.4 14856.3 16141.3 4770.9 4899.0 4911.2 4949.4 4968.7 10361.2 12104.2 12105.0 12105.4 12116.0 5146.4 5191.3 5196.7 5197.8 5199.9 5353.5 5400.6 5489.9 5521.3 5539.3 3844.6 3885.1 3921.8 3954.4 3965.5 925.0 1074.6 1075.2 1077.1 1092.9 844.3 995.8 1005.2 1022.5 1035.9 6512.2 6568.8 6586.9 6866.8 6872.4 973.8 997.8 1015.3 1027.1 1033.9 4058.3 4243.2 4388.2 4393.0 4519.5 13349.0 13578.5 14172.1 14173.3 14312.9 13849.8 13866.8 14198.4 14217.0 14220.8 10090.0 10407.5 10529.4 10532.0 10564.6 6638.4 6921.7 6925.3 6945.2 7109.4 12452.1 12474.7 12593.4 12613.6 12690.5 12800.2 12861.0 12932.5 12943.6 13083.7 9382.8 9429.9 9442.0 9505.9 9544.3 10402.5 10427.2 10504.8 10533.6 10585.8 8951.5 8974.3 9001.5 9034.0 9135.1 8631.9 8747.2 8790.3 8819.8 8834.8 8019.3 8040.4 8089.1 8100.1 8142.6 5835.4 5917.7 5947.8 6073.5 6102.6 5788.4 5940.0 5960.2 5970.1 6115.9 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | [#4] prototype.html Cycles:180 Average:4529.90 Median:4857.87 stddev:1226.49 (25.2%) stddev-sans-first:1189.21 11:14:11 INFO - PROCESS | 2429 | Values: 354.2 354.6 379.3 386.0 389.0 4890.8 4977.4 5198.2 5198.5 5271.0 4354.5 4374.3 4751.3 4981.0 4994.8 3879.5 4276.6 4368.2 4384.1 4409.8 3583.8 4151.1 4358.0 4384.6 4441.7 3924.4 3944.8 4155.0 4311.8 4354.8 3515.9 3786.6 4289.9 4290.0 4344.0 3720.7 4048.9 4092.2 4129.4 4164.2 4575.7 4882.7 4895.7 4926.2 4928.6 4141.8 4143.8 4257.4 4751.4 5047.2 4463.3 4675.3 4858.2 5486.3 5504.9 4743.3 4745.8 4762.4 4821.7 4828.5 4703.2 4706.4 5250.3 5687.2 5722.8 3961.8 4300.8 4606.7 4784.4 4862.6 4270.0 4532.3 4856.4 4914.8 4930.0 4254.6 4812.3 4828.5 5091.0 5109.3 4436.0 4993.5 5394.8 5422.5 5441.6 1516.0 1553.3 1564.7 1583.3 1634.7 1191.9 1334.9 1342.9 1355.8 1405.0 4586.8 4672.2 4878.1 5120.5 5195.9 4547.5 4602.7 4991.7 5009.0 5404.2 4519.8 4695.4 4808.9 4944.9 5237.9 2292.7 2410.7 2855.2 2878.8 2899.1 5110.3 5362.7 5677.3 5682.1 5805.1 5219.0 5239.9 5489.3 5762.8 5779.5 5665.6 5787.9 5812.5 5813.7 5833.3 4778.4 4969.6 5237.5 5428.5 5567.9 4451.8 5116.6 5166.7 5327.0 5518.4 5730.9 5739.5 5742.5 5771.7 5804.6 4035.7 4216.5 4408.2 4481.6 4533.9 4857.5 4910.4 5244.5 5535.6 5619.9 5064.9 5305.3 5316.6 5420.7 5423.0 4614.9 4787.0 5140.9 5450.5 5473.9 4923.8 4974.6 5052.3 5294.8 5316.0 5374.9 5388.2 5451.4 5455.6 5459.4 4723.0 5102.6 5371.7 5373.4 5373.5 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - PROCESS | 2429 | [#5] yui.html Cycles:180 Average:20022.25 Median:16823.92 stddev:27682.97 (164.5%) stddev-sans-first:27722.82 11:14:11 INFO - PROCESS | 2429 | Values: 754.1 784.5 819.4 858.8 880.7 14860.7 17230.0 17272.3 17272.9 17289.3 15782.0 15872.1 15988.7 16009.9 16065.2 984.4 1001.7 1007.4 1008.7 1031.6 901.9 977.5 1036.4 1036.5 1036.6 1079.3 1080.0 1080.3 1081.5 1082.3 1070.6 1071.3 1073.6 1073.7 1074.0 798.7 799.0 799.2 799.9 800.4 15359.0 15379.5 15560.9 16818.8 16829.0 14574.3 14650.9 14790.6 14838.8 14840.3 10223.3 11753.3 12050.6 12087.7 12649.6 3889.7 3907.9 3929.2 4020.5 4059.3 111875.0 113788.0 118008.0 118223.2 120307.0 10921.9 10934.7 11487.7 11597.6 12017.5 24240.6 24255.6 24500.6 24591.9 24680.4 8030.4 8063.0 8070.2 8194.8 8357.7 4577.6 4580.7 4608.2 4643.4 4760.6 3589.8 3669.7 3681.5 3690.6 3707.3 597.8 611.1 612.7 613.9 716.6 679.7 681.2 716.2 726.5 740.2 10405.0 10696.8 10987.3 11193.8 11282.7 718.3 719.9 722.0 722.2 739.3 4232.5 5231.2 5232.5 5252.7 5254.5 84849.2 89434.6 94187.0 97559.0 99245.0 50650.1 58269.7 63703.0 66337.0 84363.6 55584.2 67551.0 70623.0 71571.6 79553.4 9196.4 11034.4 11204.8 11483.9 11595.5 44991.8 48519.8 48555.8 48666.3 49424.5 58500.8 62834.7 69493.0 72263.6 73605.6 12608.6 13936.2 14030.7 14680.4 14927.8 14851.9 14941.6 15896.2 16509.0 16517.8 14408.2 14561.1 14705.4 14716.2 15087.2 14346.2 14840.2 14998.6 15347.5 15607.5 13290.3 14442.6 14444.1 14447.0 14914.8 10009.7 10530.8 10641.3 10645.6 10744.1 10878.1 11666.0 11848.8 12313.9 12334.2 11:14:11 INFO - PROCESS | 2429 | -------- Summary: end -------- 11:14:11 INFO - PROCESS | 2429 | 11:14:11 INFO - TEST-INFO | 2429: exit 0 11:14:11 INFO - TEST-OK | dromaeo_css | took 1119649ms 11:14:11 INFO - TEST-START | kraken 11:14:11 INFO - operating with platform_type : mac_ 11:14:11 INFO - Initialising browser for kraken test... 11:14:11 INFO - TEST-INFO | started process 2433 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmptGbBcd/profile http://localhost:58475/getInfo.html) 11:14:14 INFO - PROCESS | 2433 | __metrics Screen width/height:1600/1200 11:14:14 INFO - PROCESS | 2433 | colorDepth:24 11:14:14 INFO - PROCESS | 2433 | Browser inner width/height: 1024/689 11:14:14 INFO - PROCESS | 2433 | __metrics 11:14:14 INFO - PROCESS | 2433 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:14:14 INFO - PROCESS | 2433 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:14:15 INFO - TEST-INFO | 2433: exit 0 11:14:15 INFO - Browser initialized. 11:14:15 INFO - Running cycle 1/1 for kraken test... 11:14:15 INFO - TEST-INFO | started process 2435 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmptGbBcd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:14:27 INFO - PROCESS | 2435 | RSS: Main: 165056512 11:14:27 INFO - PROCESS | 2435 | 11:14:29 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58475/tests/kraken/driver.html?testName=audio-beat-detection) 11:14:30 INFO - PROCESS | 2435 | RSS: Main: 217391104 11:14:30 INFO - PROCESS | 2435 | 11:14:33 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58475/tests/kraken/driver.html?testName=audio-dft) 11:14:33 INFO - PROCESS | 2435 | RSS: Main: 233177088 11:14:33 INFO - PROCESS | 2435 | 11:14:37 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58475/tests/kraken/driver.html?testName=audio-fft) 11:14:37 INFO - PROCESS | 2435 | RSS: Main: 327688192 11:14:37 INFO - PROCESS | 2435 | 11:14:39 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58475/tests/kraken/driver.html?testName=audio-oscillator) 11:14:40 INFO - PROCESS | 2435 | RSS: Main: 229543936 11:14:40 INFO - PROCESS | 2435 | 11:14:42 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58475/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:14:43 INFO - PROCESS | 2435 | RSS: Main: 230776832 11:14:43 INFO - PROCESS | 2435 | 11:14:46 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58475/tests/kraken/driver.html?testName=imaging-darkroom) 11:14:46 INFO - PROCESS | 2435 | RSS: Main: 271273984 11:14:46 INFO - PROCESS | 2435 | 11:14:49 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58475/tests/kraken/driver.html?testName=imaging-desaturate) 11:14:50 INFO - PROCESS | 2435 | RSS: Main: 272564224 11:14:50 INFO - PROCESS | 2435 | 11:14:53 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58475/tests/kraken/driver.html?testName=json-parse-financial) 11:14:53 INFO - PROCESS | 2435 | RSS: Main: 270217216 11:14:53 INFO - PROCESS | 2435 | 11:14:55 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58475/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:14:56 INFO - PROCESS | 2435 | RSS: Main: 246808576 11:14:56 INFO - PROCESS | 2435 | 11:14:57 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:14:58 INFO - PROCESS | 2435 | RSS: Main: 253067264 11:14:58 INFO - PROCESS | 2435 | 11:15:00 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:15:01 INFO - PROCESS | 2435 | RSS: Main: 255614976 11:15:01 INFO - PROCESS | 2435 | 11:15:04 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:15:05 INFO - PROCESS | 2435 | RSS: Main: 272113664 11:15:05 INFO - PROCESS | 2435 | 11:15:08 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:15:08 INFO - PROCESS | 2435 | RSS: Main: 265781248 11:15:08 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | Cycle 1(1): loaded http://localhost:58475/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58475/tests/kraken/driver.html?testName=ai-astar) 11:15:10 INFO - PROCESS | 2435 | RSS: Main: 310349824 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | __start_tp_report 11:15:10 INFO - PROCESS | 2435 | _x_x_mozilla_page_load 11:15:10 INFO - PROCESS | 2435 | _x_x_mozilla_page_load_details 11:15:10 INFO - PROCESS | 2435 | |i|pagename|runs| 11:15:10 INFO - PROCESS | 2435 | |0;ai-astar;106;118;117;117;115;117;116;115;115;114 11:15:10 INFO - PROCESS | 2435 | |1;audio-beat-detection;128;108;104;138;97;109;124;129;150;105 11:15:10 INFO - PROCESS | 2435 | |2;audio-dft;183;185;186;184;186;184;184;186;185;193 11:15:10 INFO - PROCESS | 2435 | |3;audio-fft;81;60;77;119;86;60;59;59;75;59 11:15:10 INFO - PROCESS | 2435 | |4;audio-oscillator;90;71;109;98;98;76;82;68;69;82 11:15:10 INFO - PROCESS | 2435 | |5;imaging-gaussian-blur;117;135;90;90;89;88;88;88;89;89 11:15:10 INFO - PROCESS | 2435 | |6;imaging-darkroom;98;95;98;138;114;95;94;94;94;94 11:15:10 INFO - PROCESS | 2435 | |7;imaging-desaturate;85;86;86;84;85;83;84;85;85;86 11:15:10 INFO - PROCESS | 2435 | |8;json-parse-financial;58;59;59;59;61;61;61;62;61;63 11:15:10 INFO - PROCESS | 2435 | |9;json-stringify-tinderbox;55;64;68;63;59;58;59;58;58;58 11:15:10 INFO - PROCESS | 2435 | |10;stanford-crypto-aes;81;82;82;89;97;102;81;81;89;99 11:15:10 INFO - PROCESS | 2435 | |11;stanford-crypto-ccm;136;138;139;134;133;136;139;157;199;169 11:15:10 INFO - PROCESS | 2435 | |12;stanford-crypto-pbkdf2;164;164;163;162;163;162;164;163;162;160 11:15:10 INFO - PROCESS | 2435 | |13;stanford-crypto-sha256-iterative;65;68;76;82;81;81;62;65;67;75 11:15:10 INFO - PROCESS | 2435 | __end_tp_report 11:15:10 INFO - PROCESS | 2435 | __start_cc_report 11:15:10 INFO - PROCESS | 2435 | _x_x_mozilla_cycle_collect,2161 11:15:10 INFO - PROCESS | 2435 | __end_cc_report 11:15:10 INFO - PROCESS | 2435 | __startTimestamp1496168110733__endTimestamp 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | ------- Summary: start ------- 11:15:10 INFO - PROCESS | 2435 | Number of tests: 14 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.50 stddev:3.40 (2.9%) stddev-sans-first:1.32 11:15:10 INFO - PROCESS | 2435 | Values: 106.0 118.0 117.0 117.0 115.0 117.0 116.0 115.0 115.0 114.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:128.50 stddev:17.17 (13.4%) stddev-sans-first:17.91 11:15:10 INFO - PROCESS | 2435 | Values: 128.0 108.0 104.0 138.0 97.0 109.0 124.0 129.0 150.0 105.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#2] audio-dft Cycles:10 Average:185.60 Median:185.50 stddev:2.80 (1.5%) stddev-sans-first:2.80 11:15:10 INFO - PROCESS | 2435 | Values: 183.0 185.0 186.0 184.0 186.0 184.0 184.0 186.0 185.0 193.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#3] audio-fft Cycles:10 Average:73.50 Median:67.50 stddev:19.13 (28.3%) stddev-sans-first:20.09 11:15:10 INFO - PROCESS | 2435 | Values: 81.0 60.0 77.0 119.0 86.0 60.0 59.0 59.0 75.0 59.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:82.00 stddev:14.04 (17.1%) stddev-sans-first:14.74 11:15:10 INFO - PROCESS | 2435 | Values: 90.0 71.0 109.0 98.0 98.0 76.0 82.0 68.0 69.0 82.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#5] imaging-gaussian-blur Cycles:10 Average:96.30 Median:89.00 stddev:16.23 (18.2%) stddev-sans-first:15.39 11:15:10 INFO - PROCESS | 2435 | Values: 117.0 135.0 90.0 90.0 89.0 88.0 88.0 88.0 89.0 89.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:94.50 stddev:14.23 (15.1%) stddev-sans-first:15.04 11:15:10 INFO - PROCESS | 2435 | Values: 98.0 95.0 98.0 138.0 114.0 95.0 94.0 94.0 94.0 94.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 11:15:10 INFO - PROCESS | 2435 | Values: 85.0 86.0 86.0 84.0 85.0 83.0 84.0 85.0 85.0 86.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:61.00 stddev:1.58 (2.6%) stddev-sans-first:1.41 11:15:10 INFO - PROCESS | 2435 | Values: 58.0 59.0 59.0 59.0 61.0 61.0 61.0 62.0 61.0 63.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:59.00 stddev:3.83 (6.5%) stddev-sans-first:3.61 11:15:10 INFO - PROCESS | 2435 | Values: 55.0 64.0 68.0 63.0 59.0 58.0 59.0 58.0 58.0 58.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.50 stddev:8.29 (9.7%) stddev-sans-first:8.36 11:15:10 INFO - PROCESS | 2435 | Values: 81.0 82.0 82.0 89.0 97.0 102.0 81.0 81.0 89.0 99.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:139.00 stddev:21.33 (15.3%) stddev-sans-first:22.18 11:15:10 INFO - PROCESS | 2435 | Values: 136.0 138.0 139.0 134.0 133.0 136.0 139.0 157.0 199.0 169.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.00 stddev:1.25 (0.8%) stddev-sans-first:1.24 11:15:10 INFO - PROCESS | 2435 | Values: 164.0 164.0 163.0 162.0 163.0 162.0 164.0 163.0 162.0 160.0 11:15:10 INFO - PROCESS | 2435 | 11:15:10 INFO - PROCESS | 2435 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:75.50 stddev:7.64 (10.1%) stddev-sans-first:7.65 11:15:10 INFO - PROCESS | 2435 | Values: 65.0 68.0 76.0 82.0 81.0 81.0 62.0 65.0 67.0 75.0 11:15:10 INFO - PROCESS | 2435 | -------- Summary: end -------- 11:15:10 INFO - PROCESS | 2435 | 11:15:11 INFO - TEST-INFO | 2435: exit 0 11:15:11 INFO - TEST-OK | kraken | took 59752ms 11:15:11 INFO - SUITE-END | took 1179s 11:15:11 INFO - Completed test suite (00:19:39) 11:15:11 INFO - javascript benchmark 11:15:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7847.875128612711, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.923076923077, 3511.245082307304, 3515.8066306353157, 3532.2582257725876, 3581.015220814276, 6013.969930150349, 6033.0, 6082.939170608292, 6098.871708733887, 6108.053251745981, 6153.446189842914, 6172.783952561661, 6178.25860896693, 6189.907151392733, 6202.9689851550675, 5418.539424148962, 5430.8913821723545, 5489.560835133199, 5517.724113794295, 5608.186812912125, 5346.171343441753, 5376.247325374431, 5418.91871621924, 5600.887982240352, 5606.103023516238, 5466.863328416802, 5474.260974768415, 5475.72621368932, 5478.178273259023, 5488.423715509874, 5471.206675032098, 5477.068898287301, 5477.7261136943, 5479.972600136994, 5481.890362192754, 5294.100002999499, 5393.379761327458, 5403.081476149025, 5420.864478388072, 5443.59173062018, 6106.339326077114, 6109.2528441424865, 6117.675163990739, 6157.538184636171, 6213.409726076061, 6433.228012638467, 6449.12936753537, 6453.903191452132, 6456.128422662928, 6462.838429039265, 6543.541952063358, 6580.4406625436895, 6583.9012414813815, 6592.0, 6594.53838231324, 5732.684702341369, 6107.591005093845, 6132.049587189136, 6157.168782214344, 6163.599366041193, 6700.363465470772, 6719.731210751613, 6733.0, 6739.629320387426, 6746.291639377795, 6383.042543618494, 6387.073874288202, 6391.296957334689, 6392.616443013434, 6396.264429590564, 5554.472325129187, 6220.906686399708, 6284.560080794392, 6494.285628580852, 6550.606963582105, 6360.52296077796, 6393.264774550893, 6494.285628580852, 6495.1556297681, 6495.1556297681, 6472.838179045561, 6485.383888530582, 6486.416222539994, 6499.837504062436, 6500.70746816385, 5131.153359695609, 5203.7918483261, 5297.364316282071, 5334.359876814807, 5351.170568561879, 4662.370579971728, 4681.157391669532, 4685.390899183084, 4688.367070445514, 4695.366125573004, 6143.109249158847, 6164.445199932027, 6176.3514830943, 6177.135201071854, 6193.194884664965, 6570.113034740249, 6571.145751052332, 6579.013148027834, 6579.736810527621, 6584.670766461658, 6185.0, 6256.249250090018, 6258.780942666985, 6260.154879091356, 6289.842753931189, 5594.9720251398485, 5626.859328516738, 5691.459311365414, 5732.971335143381, 5744.281964754406, 6752.561083529555, 6772.0857684212, 6777.762778302736, 6785.321467853175, 6791.762288320083, 6529.7061632227615, 6545.901811472927, 6559.344065593594, 6560.278369379269, 6565.80302590923, 6458.257300410513, 6468.256150542559, 6722.0, 6756.797296081126, 6773.830654233683, 6435.356464353527, 6438.967805161132, 6472.546921715435, 6477.255115661759, 6492.31830657793, 6515.087887695631, 6528.183977002875, 6543.149390579194, 6561.671916404065, 6573.1454910861285, 6174.722137503733, 6247.0629405589525, 6293.748250069946, 6388.297287298301, 6396.072569477306, 6230.813075607739, 6301.0, 6335.619862808247, 6358.237011558643, 6383.0744542042075, 6108.342071131425, 6269.96865015672, 6273.0, 6284.874302514016, 6288.276848162337, 6203.906941395792, 6215.875682486416, 6253.906191407224, 6282.371762823681, 6391.520635952229, 6144.801763656044, 6296.244450665949, 6297.52768542352, 6306.400891915261, 6315.810525684236], "value": 6000.775995300756, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.4843326054695, 805.7784109369923, 805.7824387415397, 894.2622336572325, 898.4519443139679, 28600.856995715018, 28911.13266602003, 30370.240743981383, 39292.82121536358, 39650.80174599123, 41284.38073428901, 41446.79276603613, 41461.37807932883, 41475.96310092242, 41509.37735933963, 9573.042695730424, 9739.366941148837, 9747.658831940884, 9752.609895604202, 9765.95117024414, 7999.600019998977, 8159.796005099861, 8207.753767386988, 11667.299962002264, 11823.881761182407, 11944.880551194507, 11948.044156467462, 11970.521179152824, 11995.98034167097, 12004.399780010965, 11893.108016898725, 11903.28580285177, 11932.880671193263, 11940.820887686605, 11961.999999999956, 11558.364289964049, 11614.593489227836, 11638.0, 11648.0, 11649.534018639244, 39845.804625861274, 40006.0, 40039.0, 40085.0, 40132.79933600342, 4015.477987861559, 4051.250518654029, 4067.4508941292843, 4086.5504794472586, 4096.283150448659, 28295.717042829514, 28562.428751425065, 29395.570078795245, 29940.251493712833, 30656.08031759051, 3249.3988612106837, 3257.250832308582, 3284.26104126572, 3419.504171895086, 3424.075499615115, 349266.0, 351516.99999999744, 352696.0, 352860.47139528533, 354337.0, 52481.0, 52654.0, 52674.683132921986, 52690.0, 52799.0, 54912.725436372566, 54916.45083549113, 54943.0, 55036.0, 55112.44887551153, 15054.849451505344, 15416.383344666088, 15424.614384640474, 15434.768478472832, 15461.922690386476, 12958.546450874173, 13206.405711742802, 13213.603591892259, 13240.271785051915, 13240.801387978996, 10451.111655509214, 10644.414557199274, 10798.974097460728, 11759.05927525796, 12022.278663280056, 5198.116320225571, 5255.947440525622, 5259.395884255262, 5260.184671375943, 5263.763130659129, 1092.4210168610648, 1114.793763153819, 1133.4389477208833, 1135.6195674448986, 1189.1319336884123, 23894.044238230625, 25132.74867251304, 26833.926642934453, 26884.0, 27921.581176282372, 1059.5073290919759, 1088.9945550272198, 1100.8073587122383, 1121.1255220927526, 1136.4545018391218, 13187.868121318665, 13560.301495020836, 13808.585742427744, 13840.0, 13910.443582256596, 487730.0, 487798.0, 487939.5603022033, 488873.0, 489591.5520422375, 40259.79220623327, 45414.54585454103, 45434.54565454369, 45761.31358029632, 45801.77099114483, 1441.978370324446, 1457.5918742752046, 1488.1889370293216, 1495.8055452791284, 1496.4089184771738, 22466.101355946234, 24744.25767226986, 26977.595336070368, 27382.45235095247, 27594.862025690545, 35816.46275305925, 36021.09947251339, 36052.4592131113, 36081.639183607826, 36099.819500902326, 422771.0, 424639.0, 424807.0, 425162.0, 425619.0, 28641.0, 37525.37322387406, 38358.6164138355, 38421.61578384292, 38471.0, 31434.842825785723, 35202.823985879906, 42921.570784293006, 47798.5220147808, 50028.749856250484, 32188.356232874743, 32194.19514512156, 32320.676793231767, 32420.513692295106, 33413.83293083519, 31862.840685796422, 32868.50697239591, 33099.50350744789, 33133.502997454576, 33258.168545786546, 38491.65279215214, 39420.211595768495, 39542.40686389649, 39678.801605991786, 39764.2047159061, 32551.67448325581, 33266.334673305915, 33378.99863004114, 33767.3246535063, 34638.0, 25917.740822592288, 26202.606960895984, 26249.475010500064, 26385.51094453917, 26719.465610688065], "value": 19001.15841826588, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.2609875853259, 418.93087640539295, 426.188111647312, 433.66824379349805, 463.6290967226218, 19226.519337016565, 20455.488612784688, 21594.0, 21615.81113038782, 21776.237831675895, 21319.0, 21963.890180549075, 22140.22509212178, 22460.775392246072, 22999.0, 6811.557248778813, 7129.5365801223015, 7145.821354466154, 7152.678129484159, 7159.212486626467, 6806.353396427331, 6956.130483689539, 6959.652017399135, 6964.582125072487, 7019.262977387378, 6258.812235632939, 6921.792346229621, 6930.376266136047, 6942.652867356637, 7534.849303013936, 7702.614869256515, 7854.7250846220395, 7928.929594504754, 7937.047554293522, 7942.292407132465, 7663.233676632348, 7761.767146985543, 7762.107357653829, 7771.184025677336, 7775.494592851446, 21706.457338566503, 24578.262652120287, 26674.866625666746, 26816.463670726673, 27052.729472705218, 2857.414230082849, 2974.4943359628915, 2984.2688541307302, 3005.113491520007, 3020.0939718084487, 15423.0, 16097.758533622005, 16358.591035224095, 16391.852570320083, 16660.75008874868, 2036.3381900882273, 2050.507878109258, 2058.6809044597962, 2065.607534568442, 2117.8305735541276, 127119.36440317739, 144765.0, 165263.0, 176473.11763441228, 178740.0, 16329.10189939541, 18998.71501927472, 19011.904940475484, 19132.330368437037, 20078.297259595845, 31179.53230701541, 31199.532007019912, 31236.375272494424, 31446.05661830149, 31495.212619684233, 8564.914350856536, 10215.336003159771, 10453.895461045444, 10788.0, 10801.83797243042, 8821.294296456268, 8979.640814367353, 8983.326250531236, 9171.220446262143, 9194.63221471147, 8147.837043259102, 9050.818983620422, 9360.953195234117, 9391.389559678599, 9410.25910206614, 3562.1628917204434, 4004.8998775030273, 4030.919381612351, 4085.754854708727, 4091.202215567995, 549.5383877542884, 809.6923169195668, 858.6729961803458, 883.960221790021, 1033.3851358441716, 22011.950200835483, 22179.223727169796, 22290.331290061324, 22339.994700238527, 22372.664410033864, 856.2678909532385, 865.4158443050916, 875.5272153037416, 887.2946007923716, 890.0387581412059, 11245.493952772145, 11497.252678575866, 11716.9454749073, 11763.411829408495, 11772.470238839272, 803006.0, 803038.0, 804728.0, 814252.0, 825508.0, 139774.0, 154293.0, 159378.0, 162961.0, 166505.0, 1254.717688520094, 1268.632096691949, 1320.854705982341, 1321.8678132186897, 1325.2578556008666, 19385.8061419384, 19535.902320488305, 20126.39620811378, 20221.494462637962, 20274.695879561525, 61808.69095654493, 63097.684511578984, 63385.36614633795, 64115.0, 64162.39594010187, 91860.54069729875, 92812.0, 94511.0, 95810.12569622922, 96148.03851961391, 10334.948325258325, 11839.0, 11857.822132668189, 12088.879111208775, 12237.877621223673, 13254.337283135997, 13361.866381336062, 13418.798718019432, 13458.52895148665, 13857.237851918242, 9586.568604412678, 11275.0, 11553.942230288794, 11812.704682382666, 11895.762084758084, 9229.584668689791, 10785.676429707122, 10833.187510936868, 10907.181961352771, 11139.10887129044, 11614.187006909435, 11670.474828632561, 11719.472623731783, 11946.820797688215, 11958.880411195776, 11679.708007299885, 11685.76628467443, 12001.879981200076, 12013.318135460868, 12023.63929082129, 9599.0, 11303.7739245213, 11335.886641133813, 11620.418979050844, 11875.287482751062], "value": 11751.3747639864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.524794193945, 1580.5574439157026, 1597.624558228816, 1637.6560922206336, 1649.7030534503788, 10778.0, 11620.128490363215, 11622.302661840295, 11652.592159274427, 11663.06695464363, 10820.891791082087, 11108.833367499474, 11360.318380897132, 11462.31226126433, 11503.0, 2498.950020999579, 2529.6964364276314, 2532.0378256262593, 2538.9619155712585, 2541.961870571943, 2482.7517248275117, 2484.925452236436, 2533.050106210171, 2534.1510593951048, 2542.4660821227562, 2444.511097780442, 2502.9749702503013, 2505.6116301973134, 2524.772770450659, 2532.278300684305, 2443.7434069422634, 2501.524710305045, 2515.2579988903226, 2515.9119430820015, 2525.7497538718453, 2481.801455883525, 2505.2985164154065, 2511.208969174704, 2517.9496410071697, 2525.9242222733346, 11192.552297908074, 11442.771144577062, 11466.426678666117, 11473.254238474556, 11475.852414758541, 5873.882522349571, 6009.909851352233, 6041.365656606037, 6047.213862197887, 6050.667213303266, 3599.4240921452442, 3712.053426376257, 3771.30230907283, 3796.4495148203355, 3844.7116466265143, 1004.7889943111956, 1149.752803147319, 1247.5384107880143, 1257.1953949472345, 1316.9012324075636, 14098.224597647126, 14340.713185736331, 14756.409743610242, 14856.33146508399, 16141.273642686107, 4770.928436073462, 4898.951010489921, 4911.18965370717, 4949.3667089860255, 4968.701877887338, 10361.222908281909, 12104.152709310263, 12105.0, 12105.394730263451, 12116.0, 5146.356705411824, 5191.273221748958, 5196.740162991835, 5197.766100525486, 5199.896002080013, 5353.544948679406, 5400.648957817729, 5489.945100548944, 5521.282233309644, 5539.335279766373, 3844.5770965194365, 3885.0870045539277, 3921.764694118362, 3954.426608141803, 3965.5439624443557, 924.9907500924904, 1074.60776816463, 1075.1560025380195, 1077.138289368502, 1092.9016388525072, 844.2697067036969, 995.8107959487675, 1005.2410430125209, 1022.4887556221889, 1035.8705161854768, 6512.218533775977, 6568.770093046721, 6586.901196482057, 6866.759663411856, 6872.381485666314, 973.7711637765119, 997.8403455447094, 1015.3248090020202, 1027.1474676018886, 1033.8552602635637, 4058.289799285113, 4243.236217480821, 4388.188185185687, 4392.978035109868, 4519.525449827786, 13349.0, 13578.45686172542, 14172.149671019772, 14173.291335433187, 14312.926530510253, 13849.792253116211, 13866.791998120036, 14198.432062717375, 14217.0, 14220.786688199685, 10090.0, 10407.479626018669, 10529.42088185157, 10532.0, 10564.577416903237, 6638.402543771083, 6921.7231310747, 6925.272846351262, 6945.236024037453, 7109.395701365235, 12452.128351015343, 12474.688132796753, 12593.370331483573, 12613.558525451564, 12690.492380304684, 12800.16798908068, 12861.0, 12932.547360842325, 12943.611691649265, 13083.672908176992, 9382.765430864283, 9429.905700942903, 9441.952790236279, 9505.904940950779, 9544.284178686488, 10402.47987600632, 10427.21795865298, 10504.78990420172, 10533.631322903662, 10585.788284234426, 8951.462912225288, 8974.282057435521, 9001.54992250398, 9033.954830225806, 9135.132162444557, 8631.913680863363, 8747.212750852454, 8790.34072444582, 8819.823603528022, 8834.823303533765, 8019.315943651898, 8040.437169398086, 8089.110197878111, 8100.149484304063, 8142.592870356577, 5835.44563266489, 5917.6745279010065, 5947.791827286024, 6073.544484163794, 6102.603330783485, 5788.421157884178, 5940.049592065248, 5960.165576819336, 5970.104484327386, 6115.908261376172], "value": 5262.261796989271, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.2348527181287, 354.5957608326508, 379.2699054320433, 385.9771605246096, 388.986385476508, 4890.779914903829, 4977.402711674604, 5198.168293073109, 5198.478737812163, 5271.0, 4354.542773008836, 4374.278244089729, 4751.287306903966, 4980.950190498094, 4994.777324090781, 3879.515060617423, 4276.615104640589, 4368.1918845013615, 4384.057427653054, 4409.757463339515, 3583.784972901621, 4151.130630431479, 4357.978210108945, 4384.583464570861, 4441.711288766236, 3924.3917192835015, 3944.8224829882724, 4155.029154154794, 4311.779433591702, 4354.7604881731495, 3515.9472607910902, 3786.602406747293, 4289.871303860889, 4289.978550107261, 4344.044310251739, 3720.7395482316247, 4048.919021619551, 4092.161106973093, 4129.359949207877, 4164.1879833432495, 4575.702579332333, 4882.6826256293225, 4895.706257624518, 4926.187179115477, 4928.556429921288, 4141.834326626931, 4143.7720925349095, 4257.382679511454, 4751.3585665935, 5047.217681259411, 4463.285874260103, 4675.298705194214, 4858.246971719389, 5486.286782718221, 5504.926539324833, 4743.335932969387, 4745.762711864428, 4762.380890484214, 4821.710697358169, 4828.517148285178, 4703.212779143903, 4706.352776528222, 5250.343707036659, 5687.232223649754, 5722.799702010451, 3961.7821019844187, 4300.849470268526, 4606.700564463299, 4784.378030855966, 4862.586680132228, 4269.958009416703, 4532.32015197723, 4856.39295088114, 4914.778834952435, 4929.968069637147, 4254.617084462413, 4812.25410061441, 4828.493008234085, 5090.974545127251, 5109.266542399799, 4436.0, 4993.500649934977, 5394.757235924393, 5422.48486393792, 5441.6190866639745, 1515.9772603410959, 1553.3320672111101, 1564.6635973265802, 1583.279607778458, 1634.6812371587425, 1191.928484290928, 1334.8531661517225, 1342.89256859451, 1355.8204362204747, 1404.9719005619831, 4586.83035825869, 4672.159011377985, 4878.07316609843, 5120.462351453044, 5195.948040519546, 4547.47704014036, 4602.654800889946, 4991.675541089818, 5009.0, 5404.1904952176565, 4519.796609152596, 4695.43654761424, 4808.8797780055775, 4944.8763780905765, 5237.895242095136, 2292.747797742247, 2410.6745589345564, 2855.215490318551, 2878.8416637084742, 2899.144752298077, 5110.2590124431745, 5362.678239305654, 5677.344577446454, 5682.062459694187, 5805.126462401138, 5218.9739051304505, 5239.87784947097, 5489.325755644567, 5762.798302059407, 5779.479846813807, 5665.6204394155375, 5787.932395830217, 5812.534997200214, 5813.738381772746, 5833.329167145747, 4778.423120370339, 4969.552740253394, 5237.528622424, 5428.45715428454, 5567.8864227155545, 4451.799669014967, 5116.6418350716585, 5166.689998600096, 5327.0, 5518.427248234234, 5730.856728581819, 5739.483446489836, 5742.511886489695, 5771.653700777967, 5804.562083924678, 4035.717499775105, 4216.49402071741, 4408.228560002051, 4481.619062379734, 4533.886652833706, 4857.489963553776, 4910.361652985089, 5244.5279924806955, 5535.557155427637, 5619.8809660638735, 5064.898702026013, 5305.336832895888, 5316.627836051594, 5420.701861397661, 5422.972885135549, 4614.86935700742, 4786.978937692216, 5140.871478213075, 5450.5367043801725, 5473.945260547503, 4923.803047878045, 4974.602031837372, 5052.317937078447, 5294.788208471618, 5315.963387139471, 5374.865628359166, 5388.168022872243, 5451.37309209446, 5455.618106732491, 5459.426760190282, 4722.976385118052, 5102.591792656504, 5371.743867575706, 5373.361124856956, 5373.489518495893], "value": 4181.495423350865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.1289810269141, 784.4587234807982, 819.397742659145, 858.8454078265912, 880.6917578847405, 14860.702785944275, 17229.96620202789, 17272.309107635694, 17272.913635431836, 17289.308427662894, 15782.0, 15872.12703301318, 15988.680226395465, 16009.919950400232, 16065.196740162974, 984.3601658921705, 1001.6794625719773, 1007.4308015970989, 1008.6721815409984, 1031.5667419683714, 901.8951784064501, 977.4623956823754, 1036.3678156324675, 1036.4972988100765, 1036.5646428500047, 1079.3362082319356, 1080.0387654987028, 1080.2654195147297, 1081.4754843900703, 1082.2802836114015, 1070.550368845087, 1071.2822408985999, 1073.5866691323818, 1073.720832583526, 1073.9731506712317, 798.66456088443, 798.9800254993613, 799.2367289238763, 799.9240072193132, 800.3677095094868, 15359.0, 15379.461718839786, 15560.92219538895, 16818.83181168185, 16829.0, 14574.344154513072, 14650.853491465163, 14790.630234244123, 14838.777418338734, 14840.332185051699, 10223.335483193643, 11753.294802311888, 12050.638480845588, 12087.697807554794, 12649.557265495663, 3889.7082718796205, 3907.882763517099, 3929.1945151244213, 4020.4974378202724, 4059.269331520325, 111875.0, 113788.0, 118007.99999999914, 118223.2266516003, 120307.0, 10921.890781092088, 10934.726631834268, 11487.71280717972, 11597.594084207181, 12017.459214335377, 24240.636390454158, 24255.636165457534, 24500.632490512657, 24591.877040614683, 24680.382990425383, 8030.43786934909, 8062.959685201537, 8070.192980701883, 8194.795130121676, 8357.665693372197, 4577.5880170784785, 4580.748058856728, 4608.170529304691, 4643.442786865598, 4760.571548560646, 3589.8205089745406, 3669.7064234861145, 3681.502997095358, 3690.612485688964, 3707.3141468828353, 597.8475488750341, 611.1169360274449, 612.6752821004875, 613.9447449729546, 716.6022857314241, 679.7315060551055, 681.1519658025691, 716.2014353995362, 726.5241266970143, 740.1784019738086, 10404.97362098435, 10696.83954740664, 10987.340759554452, 11193.832092518618, 11282.661520154408, 718.2888939949412, 719.9496035277481, 721.9566825990457, 722.2344315026089, 739.3198257603018, 4232.470941132358, 5231.215317702299, 5232.476752324737, 5252.737363131828, 5254.527092561688, 84849.15150848536, 89434.55282723544, 94187.0, 97559.0, 99245.0, 50650.120229390275, 58269.70865145732, 63703.0, 66337.0, 84363.5781821087, 55584.16623750647, 67550.98673519901, 70623.0, 71571.64214178895, 79553.40893182105, 9196.448213107235, 11034.393108379116, 11204.83192752093, 11483.94258028738, 11595.536178552764, 44991.77504112459, 48519.787005323735, 48555.843870148325, 48666.29341544854, 49424.50575494199, 58500.829983400945, 62834.685826570574, 69493.0, 72263.63868180626, 73605.6319718398, 12608.558700445441, 13936.163830170222, 14030.719385612434, 14680.41278348854, 14927.776083358542, 14851.93848604, 14941.626459338604, 15896.205189740236, 16509.0, 16517.834821651628, 14408.20754858493, 14561.106505086496, 14705.41178352854, 14716.19060951658, 15087.245637717851, 14346.21095839697, 14840.183789891227, 14998.625034374229, 15347.462838800588, 15607.531774046796, 13290.335483225994, 14442.638934026312, 14444.133351998913, 14446.988710790143, 14914.758598738716, 10009.749756256151, 10530.78938421212, 10641.308314959502, 10645.61123841773, 10744.140468762636, 10878.12974962017, 11666.046765354888, 11848.76302473963, 12313.906635871745, 12334.198277111958], "value": 7923.022918259703, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 117.0, 117.0, 115.0, 117.0, 116.0, 115.0, 115.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 108.0, 104.0, 138.0, 97.0, 109.0, 124.0, 129.0, 150.0, 105.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 186.0, 184.0, 186.0, 184.0, 184.0, 186.0, 185.0, 193.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 77.0, 119.0, 86.0, 60.0, 59.0, 59.0, 75.0, 59.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 109.0, 98.0, 98.0, 76.0, 82.0, 68.0, 69.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 135.0, 90.0, 90.0, 89.0, 88.0, 88.0, 88.0, 89.0, 89.0], "value": 96.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 98.0, 138.0, 114.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 84.0, 85.0, 83.0, 84.0, 85.0, 85.0, 86.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 59.0, 61.0, 61.0, 61.0, 62.0, 61.0, 63.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 68.0, 63.0, 59.0, 58.0, 59.0, 58.0, 58.0, 58.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 89.0, 97.0, 102.0, 81.0, 81.0, 89.0, 99.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 139.0, 134.0, 133.0, 136.0, 139.0, 157.0, 199.0, 169.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 163.0, 162.0, 163.0, 162.0, 164.0, 163.0, 162.0, 160.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 76.0, 82.0, 81.0, 81.0, 62.0, 65.0, 67.0, 75.0], "value": 72.2, "unit": "score"}]}]} 11:15:11 INFO - Return code: 0 11:15:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:15:12 INFO - # TBPL SUCCESS # 11:15:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:15:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:15:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:15:12 INFO - 2017-05-30 11:15:12.049 screenresolution[2439:35803] starting screenresolution argv=screenresolution get 11:15:12 INFO - 2017-05-30 11:15:12.052 screenresolution[2439:35803] Display 0: 1600x1200x32@60 11:15:12 INFO - 2017-05-30 11:15:12.059 screenresolution[2440:35806] starting screenresolution argv=screenresolution list 11:15:12 INFO - Available Modes on Display 0 11:15:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:15:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:15:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:15:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:15:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:15:12 INFO - Intel Iris: 11:15:12 INFO - Chipset Model: Intel Iris 11:15:12 INFO - Type: GPU 11:15:12 INFO - Bus: Built-In 11:15:12 INFO - VRAM (Dynamic, Max): 1536 MB 11:15:12 INFO - Vendor: Intel (0x8086) 11:15:12 INFO - Device ID: 0x0a2e 11:15:12 INFO - Revision ID: 0x0009 11:15:12 INFO - Displays: 11:15:12 INFO - Display: 11:15:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:15:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:15:12 INFO - Display Serial Number: 1600x1200 60 11:15:12 INFO - Main Display: Yes 11:15:12 INFO - Mirror: Off 11:15:12 INFO - Online: Yes 11:15:12 INFO - Rotation: Supported 11:15:12 INFO - Return code: 0 11:15:12 INFO - Running post-action listener: _resource_record_post_action 11:15:12 INFO - [mozharness: 2017-05-30 18:15:12.293923Z] Finished run-tests step (success) 11:15:12 INFO - Running post-run listener: _resource_record_post_run 11:15:12 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 1908736; Write bytes: 423472640; Read time: 17; Write time: 2167 11:15:12 INFO - TinderboxPrint: CPU usage
53.8% 11:15:12 INFO - TinderboxPrint: I/O read bytes / time
1,908,736 / 17 11:15:12 INFO - TinderboxPrint: I/O write bytes / time
423,472,640 / 2,167 11:15:12 INFO - TinderboxPrint: CPU idle
2,208.9 (46.2%) 11:15:12 INFO - TinderboxPrint: CPU system
959.4 (20.1%) 11:15:12 INFO - TinderboxPrint: CPU user
1,613.5 (33.7%) 11:15:12 INFO - TinderboxPrint: Swap in / out
123,281,408 / 0 11:15:12 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119532032; Write bytes: 116504064; Read time: 10844; Write time: 305 11:15:12 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 995328; Write bytes: 295892992; Read time: 12; Write time: 1830 11:15:12 INFO - Running post-run listener: _upload_blobber_files 11:15:12 INFO - Blob upload gear active. 11:15:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:15:12 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:15:12 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:15:12 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:15:13 INFO - (blobuploader) - INFO - Open directory for files ... 11:15:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:15:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:15:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:13 INFO - (blobuploader) - INFO - Done attempting. 11:15:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:15:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:15:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:14 INFO - (blobuploader) - INFO - Done attempting. 11:15:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:15:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:15:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:15 INFO - (blobuploader) - INFO - Done attempting. 11:15:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:15:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:15:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:17 INFO - (blobuploader) - INFO - Done attempting. 11:15:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:15:17 INFO - Return code: 0 11:15:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:15:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:15:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad8936bb1bd8843739ec96fa6114201024c182ccfc62ae7378b38fe9524bd0cf60eeb1b13b1fbd8b96ca4a5965e7a8f2b4da1085437dcc66205833250ce81ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95ee70f2455ee81b3cd03b06adc1968f27649455e57f0ede5a8071aec3f1a61a5e3b80aa1ef3ecd78fd86ff8ed745febb07f3f177631eaf070b03e3040846d4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fcba9dfaf8f579e3e6e769a9975a4608e987af9980c771ed5397da70d3eefbefecb4b91fc35c3dbc8c73d88745b05b9f0ea1eccc76a6724d51cd663f617c006", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/900a0b95f038cfc32a14e87b7ba146447a682d0f423c28e0045718d4f3e1998485f27ada4d53b5ad0a9a9abe683ebef230c735fe797090ca6eb982d18ba5c202"} 11:15:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:15:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:15:17 INFO - Contents: 11:15:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad8936bb1bd8843739ec96fa6114201024c182ccfc62ae7378b38fe9524bd0cf60eeb1b13b1fbd8b96ca4a5965e7a8f2b4da1085437dcc66205833250ce81ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95ee70f2455ee81b3cd03b06adc1968f27649455e57f0ede5a8071aec3f1a61a5e3b80aa1ef3ecd78fd86ff8ed745febb07f3f177631eaf070b03e3040846d4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fcba9dfaf8f579e3e6e769a9975a4608e987af9980c771ed5397da70d3eefbefecb4b91fc35c3dbc8c73d88745b05b9f0ea1eccc76a6724d51cd663f617c006", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/900a0b95f038cfc32a14e87b7ba146447a682d0f423c28e0045718d4f3e1998485f27ada4d53b5ad0a9a9abe683ebef230c735fe797090ca6eb982d18ba5c202"} 11:15:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:15:17 INFO - Copying logs to upload dir... 11:15:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:15:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.267253 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-30 11:15:17.736073) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:17.739174) ========= 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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/Mozilla-Beta/sha512/7ad8936bb1bd8843739ec96fa6114201024c182ccfc62ae7378b38fe9524bd0cf60eeb1b13b1fbd8b96ca4a5965e7a8f2b4da1085437dcc66205833250ce81ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95ee70f2455ee81b3cd03b06adc1968f27649455e57f0ede5a8071aec3f1a61a5e3b80aa1ef3ecd78fd86ff8ed745febb07f3f177631eaf070b03e3040846d4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fcba9dfaf8f579e3e6e769a9975a4608e987af9980c771ed5397da70d3eefbefecb4b91fc35c3dbc8c73d88745b05b9f0ea1eccc76a6724d51cd663f617c006", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/900a0b95f038cfc32a14e87b7ba146447a682d0f423c28e0045718d4f3e1998485f27ada4d53b5ad0a9a9abe683ebef230c735fe797090ca6eb982d18ba5c202"} build_url:https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011427 build_url: 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7ad8936bb1bd8843739ec96fa6114201024c182ccfc62ae7378b38fe9524bd0cf60eeb1b13b1fbd8b96ca4a5965e7a8f2b4da1085437dcc66205833250ce81ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95ee70f2455ee81b3cd03b06adc1968f27649455e57f0ede5a8071aec3f1a61a5e3b80aa1ef3ecd78fd86ff8ed745febb07f3f177631eaf070b03e3040846d4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8fcba9dfaf8f579e3e6e769a9975a4608e987af9980c771ed5397da70d3eefbefecb4b91fc35c3dbc8c73d88745b05b9f0ea1eccc76a6724d51cd663f617c006", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/900a0b95f038cfc32a14e87b7ba146447a682d0f423c28e0045718d4f3e1998485f27ada4d53b5ad0a9a9abe683ebef230c735fe797090ca6eb982d18ba5c202"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:17.772365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:17.772676) ========= 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.8GIyydsL5Z/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.ZujwO4gHCJ/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005265 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:17.823223) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:15:17.823831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:15:17.824328) ========= ========= Total master_lag: 0.17 =========