builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0235 starttime: 1495671779.71 results: success (0) buildid: 20170524154444 builduid: f6aa83f1acf84482b8dc558bd009e638 revision: bdcbbe17ac8f8b2147f25d2e50748d215554a38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.706635) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.716837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.717241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.741791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.742189) ========= 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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-24 17:22:59-- 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% 149M=0s 2017-05-24 17:22:59 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129866 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.895259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.895584) ========= 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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.021799 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 17:22:59.944218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-24 17:22:59.944570) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bdcbbe17ac8f8b2147f25d2e50748d215554a38a --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 bdcbbe17ac8f8b2147f25d2e50748d215554a38a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pDlvuj5s2q/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.mdLtLZqKSN/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-24 17:23:00,031 truncating revision to first 12 chars 2017-05-24 17:23:00,031 Setting DEBUG logging. 2017-05-24 17:23:00,031 attempt 1/10 2017-05-24 17:23:00,031 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bdcbbe17ac8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 17:23:01,746 unpacking tar archive at: mozilla-beta-bdcbbe17ac8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.985764 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-24 17:23:01.952919) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 17:23:01.953250) ========= script_repo_revision: bdcbbe17ac8f8b2147f25d2e50748d215554a38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 17:23:01.953645) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 17:23:01.953910) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 17:23:01.972755) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-24 17:23:01.973138) ========= /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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23:02 INFO - MultiFileLogger online at 20170524 17:23:02 in /builds/slave/test 17:23:02 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 17:23:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:23:02 INFO - {'append_to_log': False, 17:23:02 INFO - 'base_work_dir': '/builds/slave/test', 17:23:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:23:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:23:02 INFO - 'branch': 'Mozilla-Beta', 17:23:02 INFO - 'buildbot_json_path': 'buildprops.json', 17:23:02 INFO - 'config_files': ('talos/mac_config.py',), 17:23:02 INFO - 'default_actions': ('clobber', 17:23:02 INFO - 'read-buildbot-config', 17:23:02 INFO - 'download-and-extract', 17:23:02 INFO - 'populate-webroot', 17:23:02 INFO - 'create-virtualenv', 17:23:02 INFO - 'install', 17:23:02 INFO - 'run-tests'), 17:23:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:23:02 INFO - 'download_minidump_stackwalk': True, 17:23:02 INFO - 'download_symbols': 'ondemand', 17:23:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:23:02 INFO - 'tooltool.py': '/tools/tooltool.py', 17:23:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:23:02 INFO - '/tools/misc-python/virtualenv.py')}, 17:23:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:23:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:23:02 INFO - 'gecko_profile': False, 17:23:02 INFO - 'gecko_profile_interval': 0, 17:23:02 INFO - 'installer_path': 'installer.exe', 17:23:02 INFO - 'log_level': 'info', 17:23:02 INFO - 'log_name': 'talos', 17:23:02 INFO - 'log_to_console': True, 17:23:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:23:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:23:02 INFO - 'opt_config_files': (), 17:23:02 INFO - 'pip_index': False, 17:23:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:23:02 INFO - 'cmd': ('bash', 17:23:02 INFO - '-c', 17:23:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:23:02 INFO - 'enabled': True, 17:23:02 INFO - 'halt_on_failure': False, 17:23:02 INFO - 'name': 'check_screen_resolution'},), 17:23:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:23:02 INFO - 'cmd': ('bash', 17:23:02 INFO - '-c', 17:23:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:23:02 INFO - 'enabled': True, 17:23:02 INFO - 'halt_on_failure': False, 17:23:02 INFO - 'name': 'check_screen_resolution'},), 17:23:02 INFO - 'run_cmd_checks_enabled': True, 17:23:02 INFO - 'suite': 'dromaeojs', 17:23:02 INFO - 'system_bits': '32', 17:23:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:23:02 INFO - 'title': 't-yosemite-r7-0235', 17:23:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:23:02 INFO - 'use_talos_json': True, 17:23:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:23:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:23:02 INFO - 'work_dir': 'build'} 17:23:02 INFO - [mozharness: 2017-05-25 00:23:02.179656Z] Running clobber step. 17:23:02 INFO - Running pre-action listener: _resource_record_pre_action 17:23:02 INFO - Running main action method: clobber 17:23:02 INFO - rmtree: /builds/slave/test/build 17:23:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:23:04 INFO - Running post-action listener: _resource_record_post_action 17:23:04 INFO - [mozharness: 2017-05-25 00:23:04.589171Z] Finished clobber step (success) 17:23:04 INFO - [mozharness: 2017-05-25 00:23:04.589325Z] Running read-buildbot-config step. 17:23:04 INFO - Running pre-action listener: _resource_record_pre_action 17:23:04 INFO - Running main action method: read_buildbot_config 17:23:04 INFO - Using buildbot properties: 17:23:04 INFO - { 17:23:04 INFO - "project": "", 17:23:04 INFO - "product": "firefox", 17:23:04 INFO - "script_repo_revision": "production", 17:23:04 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 17:23:04 INFO - "repository": "", 17:23:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 17:23:04 INFO - "buildid": "20170524154444", 17:23:04 INFO - "pgo_build": "False", 17:23:04 INFO - "basedir": "/builds/slave/test", 17:23:04 INFO - "buildnumber": 3, 17:23:04 INFO - "slavename": "t-yosemite-r7-0235", 17:23:04 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:23:04 INFO - "platform": "yosemite_r7_devedition", 17:23:04 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 17:23:04 INFO - "branch": "mozilla-beta", 17:23:04 INFO - "repo_path": "releases/mozilla-beta", 17:23:04 INFO - "revision": "bdcbbe17ac8f8b2147f25d2e50748d215554a38a", 17:23:04 INFO - "stage_platform": "macosx64-devedition", 17:23:04 INFO - "builduid": "f6aa83f1acf84482b8dc558bd009e638", 17:23:04 INFO - "slavebuilddir": "test" 17:23:04 INFO - } 17:23:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:23:04 INFO - Running post-action listener: _resource_record_post_action 17:23:04 INFO - [mozharness: 2017-05-25 00:23:04.592648Z] Finished read-buildbot-config step (success) 17:23:04 INFO - [mozharness: 2017-05-25 00:23:04.592794Z] Running download-and-extract step. 17:23:04 INFO - Running pre-action listener: _resource_record_pre_action 17:23:04 INFO - Running main action method: download_and_extract 17:23:04 INFO - mkdir: /builds/slave/test/build/tests 17:23:04 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')]} 17:23:04 INFO - trying https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:23:04 INFO - Downloading https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/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 17:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/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 17:23:06 INFO - Downloaded 1287 bytes. 17:23:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:23:06 INFO - Using the following test package requirements: 17:23:06 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:23:06 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:23:06 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:23:06 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:23:06 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:23:06 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:23:06 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:23:06 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:23:06 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:23:06 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:23:06 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:23:06 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:23:06 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:23:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:23:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:23:06 INFO - Fetch https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:23:07 INFO - Expected file size: 51963792 17:23:07 INFO - Obtained file size: 51963792 17:23:23 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 17:23:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:23:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:23:23 INFO - Fetch https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:23:25 INFO - Expected file size: 51963792 17:23:25 INFO - Obtained file size: 51963792 17:23:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:23:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:23:40 INFO - Fetch https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:23:42 INFO - Expected file size: 14404449 17:23:42 INFO - Obtained file size: 14404449 17:23:42 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')]} 17:23:42 INFO - trying https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:23:42 INFO - Downloading https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/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 17:23:44 INFO - Downloaded 74888320 bytes. 17:23:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:23:44 INFO - mkdir: /builds/slave/test/properties 17:23:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:23:44 INFO - Writing to file /builds/slave/test/properties/build_url 17:23:44 INFO - Contents: 17:23:44 INFO - build_url:https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:23:45 INFO - Running post-action listener: _resource_record_post_action 17:23:45 INFO - Running post-action listener: set_extra_try_arguments 17:23:45 INFO - [mozharness: 2017-05-25 00:23:45.205643Z] Finished download-and-extract step (success) 17:23:45 INFO - [mozharness: 2017-05-25 00:23:45.205805Z] Running populate-webroot step. 17:23:45 INFO - Running pre-action listener: _resource_record_pre_action 17:23:45 INFO - Running main action method: populate_webroot 17:23:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:23:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:23:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:23:45 INFO - '--tppagecycles', 17:23:45 INFO - '7'], 17:23:45 INFO - 'tests': ['tsvgm']}}, 17:23:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:23:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:23:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:23:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:23:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['damp', 'tps']}, 17:23:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'tests': ['damp', 'tps']}, 17:23:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['dromaeo_dom']}, 17:23:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:23:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['basic_compositor_video']}, 17:23:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:23:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['a11yr', 17:23:45 INFO - 'ts_paint', 17:23:45 INFO - 'tpaint', 17:23:45 INFO - 'sessionrestore', 17:23:45 INFO - 'sessionrestore_no_auto_restore', 17:23:45 INFO - 'tabpaint']}, 17:23:45 INFO - 'other-e10s': {'tests': ['a11yr', 17:23:45 INFO - 'ts_paint', 17:23:45 INFO - 'tpaint', 17:23:45 INFO - 'sessionrestore', 17:23:45 INFO - 'sessionrestore_no_auto_restore', 17:23:45 INFO - 'tabpaint']}, 17:23:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:23:45 INFO - 'ts_paint', 17:23:45 INFO - 'tpaint', 17:23:45 INFO - 'sessionrestore', 17:23:45 INFO - 'sessionrestore_no_auto_restore', 17:23:45 INFO - 'tabpaint']}, 17:23:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:23:45 INFO - 'ts_paint', 17:23:45 INFO - 'tpaint', 17:23:45 INFO - 'sessionrestore', 17:23:45 INFO - 'sessionrestore_no_auto_restore', 17:23:45 INFO - 'tabpaint']}, 17:23:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['a11yr', 17:23:45 INFO - 'ts_paint', 17:23:45 INFO - 'tpaint', 17:23:45 INFO - 'sessionrestore', 17:23:45 INFO - 'sessionrestore_no_auto_restore', 17:23:45 INFO - 'tabpaint']}, 17:23:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['a11yr', 17:23:45 INFO - 'ts_paint', 17:23:45 INFO - 'tpaint', 17:23:45 INFO - 'sessionrestore', 17:23:45 INFO - 'sessionrestore_no_auto_restore', 17:23:45 INFO - 'tabpaint']}, 17:23:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['tsvgx', 17:23:45 INFO - 'tsvgr_opacity', 17:23:45 INFO - 'tart', 17:23:45 INFO - 'tscrollx', 17:23:45 INFO - 'cart', 17:23:45 INFO - 'tsvg_static']}, 17:23:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:23:45 INFO - 'tsvgr_opacity', 17:23:45 INFO - 'tart', 17:23:45 INFO - 'tscrollx', 17:23:45 INFO - 'cart', 17:23:45 INFO - 'tsvg_static']}, 17:23:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'talos_options': ['--disable-e10s'], 17:23:45 INFO - 'tests': ['tp5o']}, 17:23:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:23:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'talos_options': ['--disable-e10s', 17:23:45 INFO - '--xperf_path', 17:23:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:45 INFO - 'tests': ['tp5n']}, 17:23:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:23:45 INFO - 'talos_options': ['--xperf_path', 17:23:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:45 INFO - 'tests': ['tp5n']}}, 17:23:45 INFO - 'talos.zip': {'path': '', 17:23:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:23:45 INFO - Running post-action listener: _resource_record_post_action 17:23:45 INFO - [mozharness: 2017-05-25 00:23:45.224542Z] Finished populate-webroot step (success) 17:23:45 INFO - [mozharness: 2017-05-25 00:23:45.224677Z] Running create-virtualenv step. 17:23:45 INFO - Running pre-action listener: _resource_record_pre_action 17:23:45 INFO - Running main action method: create_virtualenv 17:23:45 INFO - Creating virtualenv /builds/slave/test/build/venv 17:23:45 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 17:23:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:23:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:23:45 INFO - Using real prefix '/tools/python27' 17:23:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:23:46 INFO - Installing distribute.............................................................................................................................................................................................done. 17:23:48 INFO - Installing pip.................done. 17:23:48 INFO - Return code: 0 17:23:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:23:49 INFO - Reading from file tmpfile_stdout 17:23:49 INFO - Output received: 17:23:49 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) 17:23:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:23:49 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')]} 17:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:49 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')]} 17:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:49 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23:49 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 17:23:49 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 17:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:49 INFO - 'HOME': '/Users/cltbld', 17:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:49 INFO - 'LOGNAME': 'cltbld', 17:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:49 INFO - 'MOZ_NO_REMOTE': '1', 17:23:49 INFO - 'NO_EM_RESTART': '1', 17:23:49 INFO - 'PAGER': '/bin/cat', 17:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:49 INFO - 'PWD': '/builds/slave/test', 17:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:49 INFO - 'SHELL': '/bin/bash', 17:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:23:49 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:49 INFO - 'USER': 'cltbld', 17:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:49 INFO - 'XPC_FLAGS': '0x0', 17:23:49 INFO - 'XPC_SERVICE_NAME': '0', 17:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:49 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 17:23:49 INFO - Cleaning up... 17:23:49 INFO - Return code: 0 17:23:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:23:49 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')]} 17:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:49 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')]} 17:23:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:49 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23:49 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 17:23:49 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 17:23:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:23:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:49 INFO - 'HOME': '/Users/cltbld', 17:23:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:49 INFO - 'LOGNAME': 'cltbld', 17:23:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:49 INFO - 'MOZ_NO_REMOTE': '1', 17:23:49 INFO - 'NO_EM_RESTART': '1', 17:23:49 INFO - 'PAGER': '/bin/cat', 17:23:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:49 INFO - 'PWD': '/builds/slave/test', 17:23:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:49 INFO - 'SHELL': '/bin/bash', 17:23:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:23:49 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:23:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:49 INFO - 'USER': 'cltbld', 17:23:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:49 INFO - 'XPC_FLAGS': '0x0', 17:23:49 INFO - 'XPC_SERVICE_NAME': '0', 17:23:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:49 INFO - Downloading/unpacking psutil>=3.1.1 17:23:49 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 17:23:49 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 17:23:49 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 17:23:49 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 17:23:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:23:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:51 INFO - Installing collected packages: psutil 17:23:51 INFO - Running setup.py install for psutil 17:23:51 INFO - building 'psutil._psutil_osx' extension 17:23:51 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 17:23:51 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 17:23:52 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 17:23:52 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 17:23:52 INFO - building 'psutil._psutil_posix' extension 17:23:52 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 17:23:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:23:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:23:52 INFO - ^ 17:23:52 INFO - 1 warning generated. 17:23:52 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 17:23:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:52 INFO - Successfully installed psutil 17:23:52 INFO - Cleaning up... 17:23:52 INFO - Return code: 0 17:23:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:23:52 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')]} 17:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:52 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')]} 17:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:52 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23:52 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 17:23:52 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 17:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:52 INFO - 'HOME': '/Users/cltbld', 17:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:52 INFO - 'LOGNAME': 'cltbld', 17:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:52 INFO - 'MOZ_NO_REMOTE': '1', 17:23:52 INFO - 'NO_EM_RESTART': '1', 17:23:52 INFO - 'PAGER': '/bin/cat', 17:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:52 INFO - 'PWD': '/builds/slave/test', 17:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:52 INFO - 'SHELL': '/bin/bash', 17:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:23:52 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:52 INFO - 'USER': 'cltbld', 17:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:52 INFO - 'XPC_FLAGS': '0x0', 17:23:52 INFO - 'XPC_SERVICE_NAME': '0', 17:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:23:52 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 17:23:52 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 17:23:52 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 17:23:52 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 17:23:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:23:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:23:54 INFO - Installing collected packages: mozsystemmonitor 17:23:54 INFO - Running setup.py install for mozsystemmonitor 17:23:54 INFO - Successfully installed mozsystemmonitor 17:23:54 INFO - Cleaning up... 17:23:54 INFO - Return code: 0 17:23:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23: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')]} 17:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23: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', '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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:23: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 jsonschema==2.5.1 17:23:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:23:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:54 INFO - 'HOME': '/Users/cltbld', 17:23:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:54 INFO - 'LOGNAME': 'cltbld', 17:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:54 INFO - 'MOZ_NO_REMOTE': '1', 17:23:54 INFO - 'NO_EM_RESTART': '1', 17:23:54 INFO - 'PAGER': '/bin/cat', 17:23:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:54 INFO - 'PWD': '/builds/slave/test', 17:23:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:54 INFO - 'SHELL': '/bin/bash', 17:23:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:23:54 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:23:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:54 INFO - 'USER': 'cltbld', 17:23:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:54 INFO - 'XPC_FLAGS': '0x0', 17:23:54 INFO - 'XPC_SERVICE_NAME': '0', 17:23:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:54 INFO - Downloading/unpacking jsonschema==2.5.1 17:23: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 17:23: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 17:23: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 17:23: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 17:23:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:23:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:23:56 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 17:23:56 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 17:23:56 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 17:23:56 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 17:23:56 INFO - Downloading functools32-3.2.3-2.tar.gz 17:23:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:23:56 INFO - warning: no files found matching '*.txt' 17:23:56 INFO - no previously-included directories found matching 'build' 17:23:56 INFO - no previously-included directories found matching 'dist' 17:23:56 INFO - no previously-included directories found matching '.git*' 17:23:56 INFO - Installing collected packages: jsonschema, functools32 17:23:56 INFO - Running setup.py install for functools32 17:23:56 INFO - warning: no files found matching '*.txt' 17:23:56 INFO - no previously-included directories found matching 'build' 17:23:56 INFO - no previously-included directories found matching 'dist' 17:23:56 INFO - no previously-included directories found matching '.git*' 17:23:56 INFO - Successfully installed jsonschema functools32 17:23:56 INFO - Cleaning up... 17:23:56 INFO - Return code: 0 17:23:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:23:56 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')]} 17:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23: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')]} 17:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23: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', '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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:23: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 functools32==3.2.3-2 17:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:57 INFO - 'HOME': '/Users/cltbld', 17:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:57 INFO - 'LOGNAME': 'cltbld', 17:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:57 INFO - 'MOZ_NO_REMOTE': '1', 17:23:57 INFO - 'NO_EM_RESTART': '1', 17:23:57 INFO - 'PAGER': '/bin/cat', 17:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:57 INFO - 'PWD': '/builds/slave/test', 17:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:57 INFO - 'SHELL': '/bin/bash', 17:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:23:57 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:57 INFO - 'USER': 'cltbld', 17:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:57 INFO - 'XPC_FLAGS': '0x0', 17:23:57 INFO - 'XPC_SERVICE_NAME': '0', 17:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:23:57 INFO - Cleaning up... 17:23:57 INFO - Return code: 0 17:23:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23: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')]} 17:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23: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', '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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:23: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 blobuploader==1.2.4 17:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:57 INFO - 'HOME': '/Users/cltbld', 17:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:57 INFO - 'LOGNAME': 'cltbld', 17:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:57 INFO - 'MOZ_NO_REMOTE': '1', 17:23:57 INFO - 'NO_EM_RESTART': '1', 17:23:57 INFO - 'PAGER': '/bin/cat', 17:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:57 INFO - 'PWD': '/builds/slave/test', 17:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:57 INFO - 'SHELL': '/bin/bash', 17:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:23:57 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:57 INFO - 'USER': 'cltbld', 17:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:57 INFO - 'XPC_FLAGS': '0x0', 17:23:57 INFO - 'XPC_SERVICE_NAME': '0', 17:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:58 INFO - Downloading/unpacking blobuploader==1.2.4 17:23:58 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 17:23:58 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 17:23:58 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 17:23:58 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 17:23:59 INFO - Downloading blobuploader-1.2.4.tar.gz 17:23:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:24:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:24: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 17:24: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 17:24: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 17:24: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 17:24:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:24:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:24: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 17:24: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 17:24: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 17:24: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 17:24:00 INFO - Downloading docopt-0.6.1.tar.gz 17:24:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:24:00 INFO - Installing collected packages: blobuploader, requests, docopt 17:24:00 INFO - Running setup.py install for blobuploader 17:24:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:24:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:24:01 INFO - Running setup.py install for requests 17:24:01 INFO - Running setup.py install for docopt 17:24:01 INFO - Successfully installed blobuploader requests docopt 17:24:01 INFO - Cleaning up... 17:24:01 INFO - Return code: 0 17:24:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:24: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')]} 17:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24: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')]} 17:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:01 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:24:01 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 17:24:01 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 17:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:01 INFO - 'HOME': '/Users/cltbld', 17:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:01 INFO - 'LOGNAME': 'cltbld', 17:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:01 INFO - 'MOZ_NO_REMOTE': '1', 17:24:01 INFO - 'NO_EM_RESTART': '1', 17:24:01 INFO - 'PAGER': '/bin/cat', 17:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:01 INFO - 'PWD': '/builds/slave/test', 17:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:01 INFO - 'SHELL': '/bin/bash', 17:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:24:01 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:01 INFO - 'USER': 'cltbld', 17:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:01 INFO - 'XPC_FLAGS': '0x0', 17:24:01 INFO - 'XPC_SERVICE_NAME': '0', 17:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-WhAHdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-QadgpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-NQWtj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-iwkSon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-uHMRt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-mZSF3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-K_2EIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-jyK0bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-W8JpDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:24:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:24:02 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-MqFsrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-M9hquz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Aosnz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-jS7hoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-snZKWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-e8NCWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ADYhTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:24:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:24:03 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-whWOm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:24:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:24:03 INFO - Running setup.py install for manifestparser 17:24:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:24:03 INFO - Running setup.py install for mozcrash 17:24:04 INFO - Running setup.py install for mozdebug 17:24:04 INFO - Running setup.py install for mozdevice 17:24:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Running setup.py install for mozfile 17:24:04 INFO - Running setup.py install for mozhttpd 17:24:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Running setup.py install for mozinfo 17:24:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Running setup.py install for mozInstall 17:24:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:24:04 INFO - Running setup.py install for mozleak 17:24:05 INFO - Running setup.py install for mozlog 17:24:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:24:05 INFO - Running setup.py install for moznetwork 17:24:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:24:05 INFO - Running setup.py install for mozprocess 17:24:05 INFO - Running setup.py install for mozprofile 17:24:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:24:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:24:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:24:05 INFO - Running setup.py install for mozrunner 17:24:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:24:05 INFO - Running setup.py install for mozscreenshot 17:24:06 INFO - Running setup.py install for moztest 17:24:06 INFO - Running setup.py install for mozversion 17:24:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:24:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:24:06 INFO - Cleaning up... 17:24:06 INFO - Return code: 0 17:24:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:24:06 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')]} 17:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:06 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')]} 17:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:06 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:24:06 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 17:24:06 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 17:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:06 INFO - 'HOME': '/Users/cltbld', 17:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:06 INFO - 'LOGNAME': 'cltbld', 17:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:06 INFO - 'MOZ_NO_REMOTE': '1', 17:24:06 INFO - 'NO_EM_RESTART': '1', 17:24:06 INFO - 'PAGER': '/bin/cat', 17:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:06 INFO - 'PWD': '/builds/slave/test', 17:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:06 INFO - 'SHELL': '/bin/bash', 17:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:24:06 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:06 INFO - 'USER': 'cltbld', 17:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:06 INFO - 'XPC_FLAGS': '0x0', 17:24:06 INFO - 'XPC_SERVICE_NAME': '0', 17:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:24:06 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-WKzOYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:24:06 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)) 17:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:24:06 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-OwgGKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:24:06 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)) 17:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:24:06 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-pnM0k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:24:06 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)) 17:24:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:24:06 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-7e8b1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-eENYaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-gc0fL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-yPjW86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Pcfxzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-3hcSNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-7T0KEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-jk8UbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-XdQqOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-LoxCxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:24:07 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)) 17:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:24:07 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-AWKup3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:24:08 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)) 17:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:24:08 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ytjSny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:24:08 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)) 17:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:24:08 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ELDHdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:24:08 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)) 17:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:24:08 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-JkZBhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:24:08 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)) 17:24:08 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)) 17:24:08 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)) 17:24:08 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)) 17:24:08 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)) 17:24:08 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)) 17:24:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:24:08 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 17:24:08 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 17:24:08 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 17:24:08 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 17:24:09 INFO - Downloading blessings-1.6.tar.gz 17:24:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:24:09 INFO - Installing collected packages: blessings 17:24:09 INFO - Running setup.py install for blessings 17:24:10 INFO - Successfully installed blessings 17:24:10 INFO - Cleaning up... 17:24:10 INFO - Return code: 0 17:24:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:24:10 INFO - Reading from file tmpfile_stdout 17:24:10 INFO - Current package versions: 17:24:10 INFO - blessings == 1.6 17:24:10 INFO - blobuploader == 1.2.4 17:24:10 INFO - docopt == 0.6.1 17:24:10 INFO - functools32 == 3.2.3-2 17:24:10 INFO - jsonschema == 2.5.1 17:24:10 INFO - manifestparser == 1.1 17:24:10 INFO - mozInstall == 1.12 17:24:10 INFO - mozcrash == 1.0 17:24:10 INFO - mozdebug == 0.1 17:24:10 INFO - mozdevice == 0.49 17:24:10 INFO - mozfile == 1.2 17:24:10 INFO - mozhttpd == 0.7 17:24:10 INFO - mozinfo == 0.9 17:24:10 INFO - mozleak == 0.1 17:24:10 INFO - mozlog == 3.4 17:24:10 INFO - moznetwork == 0.27 17:24:10 INFO - mozprocess == 0.25 17:24:10 INFO - mozprofile == 0.28 17:24:10 INFO - mozrunner == 6.13 17:24:10 INFO - mozscreenshot == 0.1 17:24:10 INFO - mozsystemmonitor == 0.3 17:24:10 INFO - moztest == 0.8 17:24:10 INFO - mozversion == 1.4 17:24:10 INFO - psutil == 3.1.1 17:24:10 INFO - requests == 1.2.3 17:24:10 INFO - wsgiref == 0.1.2 17:24:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:24:10 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')]} 17:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:10 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')]} 17:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:10 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:24:10 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 17:24:10 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 17:24:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:24:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:10 INFO - 'HOME': '/Users/cltbld', 17:24:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:10 INFO - 'LOGNAME': 'cltbld', 17:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:10 INFO - 'MOZ_NO_REMOTE': '1', 17:24:10 INFO - 'NO_EM_RESTART': '1', 17:24:10 INFO - 'PAGER': '/bin/cat', 17:24:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:10 INFO - 'PWD': '/builds/slave/test', 17:24:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:10 INFO - 'SHELL': '/bin/bash', 17:24:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:24:10 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:24:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:10 INFO - 'USER': 'cltbld', 17:24:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:10 INFO - 'XPC_FLAGS': '0x0', 17:24:10 INFO - 'XPC_SERVICE_NAME': '0', 17:24:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 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)) 17:24:10 INFO - Cleaning up... 17:24:10 INFO - Return code: 0 17:24:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:24:10 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')]} 17:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:10 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')]} 17:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:10 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 0x107cf6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cf9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbaea558330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, '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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 17:24:10 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 17:24:10 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 17:24:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:24:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:10 INFO - 'HOME': '/Users/cltbld', 17:24:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:10 INFO - 'LOGNAME': 'cltbld', 17:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:10 INFO - 'MOZ_NO_REMOTE': '1', 17:24:10 INFO - 'NO_EM_RESTART': '1', 17:24:10 INFO - 'PAGER': '/bin/cat', 17:24:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:10 INFO - 'PWD': '/builds/slave/test', 17:24:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:10 INFO - 'SHELL': '/bin/bash', 17:24:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:24:10 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:24:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:10 INFO - 'USER': 'cltbld', 17:24:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:10 INFO - 'XPC_FLAGS': '0x0', 17:24:10 INFO - 'XPC_SERVICE_NAME': '0', 17:24:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:24:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:24:10 INFO - Cleaning up... 17:24:10 INFO - Return code: 0 17:24:10 INFO - Running post-action listener: _resource_record_post_action 17:24:10 INFO - Running post-action listener: _start_resource_monitoring 17:24:10 INFO - Starting resource monitoring. 17:24:10 INFO - [mozharness: 2017-05-25 00:24:10.954937Z] Finished create-virtualenv step (success) 17:24:10 INFO - [mozharness: 2017-05-25 00:24:10.955903Z] Running install step. 17:24:10 INFO - Running pre-action listener: _resource_record_pre_action 17:24:10 INFO - Running main action method: install 17:24:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:24:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:24:11 INFO - Reading from file tmpfile_stdout 17:24:11 INFO - Detecting whether we're running mozinstall >=1.0... 17:24:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:24:11 INFO - Reading from file tmpfile_stdout 17:24:11 INFO - Output received: 17:24:11 INFO - Usage: mozinstall [options] installer 17:24:11 INFO - Options: 17:24:11 INFO - -h, --help show this help message and exit 17:24:11 INFO - -d DEST, --destination=DEST 17:24:11 INFO - Directory to install application into. [default: 17:24:11 INFO - "/builds/slave/test"] 17:24:11 INFO - --app=APP Application being installed. [default: firefox] 17:24:11 INFO - mkdir: /builds/slave/test/build/application 17:24:11 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'] 17:24:11 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 17:24:31 INFO - Reading from file tmpfile_stdout 17:24:31 INFO - Output received: 17:24:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:24:31 INFO - Running post-action listener: _resource_record_post_action 17:24:31 INFO - [mozharness: 2017-05-25 00:24:31.120599Z] Finished install step (success) 17:24:31 INFO - [mozharness: 2017-05-25 00:24:31.120743Z] Running run-tests step. 17:24:31 INFO - Running pre-action listener: _resource_record_pre_action 17:24:31 INFO - Running main action method: run_tests 17:24:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:24:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:24:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:24:31 INFO - 2017-05-24 17:24:31.135 screenresolution[2124:16611] starting screenresolution argv=screenresolution get 17:24:31 INFO - 2017-05-24 17:24:31.138 screenresolution[2124:16611] Display 0: 1600x1200x32@60 17:24:31 INFO - 2017-05-24 17:24:31.144 screenresolution[2125:16613] starting screenresolution argv=screenresolution list 17:24:31 INFO - Available Modes on Display 0 17:24:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:24:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:24:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:24:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:24:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:24:31 INFO - Intel Iris: 17:24:31 INFO - Chipset Model: Intel Iris 17:24:31 INFO - Type: GPU 17:24:31 INFO - Bus: Built-In 17:24:31 INFO - VRAM (Dynamic, Max): 1536 MB 17:24:31 INFO - Vendor: Intel (0x8086) 17:24:31 INFO - Device ID: 0x0a2e 17:24:31 INFO - Revision ID: 0x0009 17:24:31 INFO - Displays: 17:24:31 INFO - Display: 17:24:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:24:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:24:31 INFO - Display Serial Number: 1600x1200 60 17:24:31 INFO - Main Display: Yes 17:24:31 INFO - Mirror: Off 17:24:31 INFO - Online: Yes 17:24:31 INFO - Rotation: Supported 17:24:31 INFO - Return code: 0 17:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:24:31 INFO - Python 2.7.3 17:24:31 INFO - Return code: 0 17:24:31 INFO - grabbing minidump binary from tooltool 17:24: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')]} 17:24: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 0x107ad4d30>, '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 0x1079fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107ac9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:24: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 17:24: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 17:24: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 17:24:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:24:31 INFO - Return code: 0 17:24:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:24:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:24:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:24:31 INFO - ENV: RUST_BACKTRACE is now 1 17:24:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:24:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:24:31 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 17:24:31 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0235 --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 17:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pDlvuj5s2q/Render', 17:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:31 INFO - 'HOME': '/Users/cltbld', 17:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:31 INFO - 'LOGNAME': 'cltbld', 17:24:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:24:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:31 INFO - 'MOZ_NO_REMOTE': '1', 17:24:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:24:31 INFO - 'NO_EM_RESTART': '1', 17:24:31 INFO - 'PAGER': '/bin/cat', 17:24:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:31 INFO - 'PWD': '/builds/slave/test', 17:24:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:31 INFO - 'RUST_BACKTRACE': '1', 17:24:31 INFO - 'SHELL': '/bin/bash', 17:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdLtLZqKSN/Listeners', 17:24:31 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 17:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:31 INFO - 'USER': 'cltbld', 17:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:31 INFO - 'XPC_FLAGS': '0x0', 17:24:31 INFO - 'XPC_SERVICE_NAME': '0', 17:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:31 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 17:24:31 INFO - mozversion application_buildid: 20170524154444 17:24:31 INFO - mozversion application_changeset: bdcbbe17ac8f8b2147f25d2e50748d215554a38a 17:24:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:24:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:24:31 INFO - mozversion application_name: Firefox 17:24:31 INFO - mozversion application_remotingname: firefox-dev 17:24:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:24:31 INFO - mozversion application_vendor: Mozilla 17:24:31 INFO - mozversion application_version: 54.0 17:24:31 INFO - mozversion platform_buildid: 20170524154444 17:24:31 INFO - mozversion platform_changeset: bdcbbe17ac8f8b2147f25d2e50748d215554a38a 17:24:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:24:31 INFO - mozversion platform_version: 54.0 17:24:31 INFO - using testdate: 1495671871 17:24:31 INFO - actual date: 1495671871 17:24:31 INFO - starting webserver on 'localhost:49571' 17:24:31 INFO - SUITE-START | Running 2 tests 17:24:31 INFO - TEST-START | dromaeo_css 17:24:31 INFO - operating with platform_type : mac_ 17:24:31 INFO - Initialising browser for dromaeo_css test... 17:24:31 INFO - TEST-INFO | started process 2135 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmplx5dbf/profile http://localhost:49571/getInfo.html) 17:24:34 INFO - PROCESS | 2135 | __metrics Screen width/height:1600/1200 17:24:34 INFO - PROCESS | 2135 | colorDepth:24 17:24:34 INFO - PROCESS | 2135 | Browser inner width/height: 1024/689 17:24:34 INFO - PROCESS | 2135 | __metrics 17:24:34 INFO - PROCESS | 2135 | 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" 17:24:34 INFO - PROCESS | 2135 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:24:34 INFO - TEST-INFO | 2135: exit 0 17:24:34 INFO - Browser initialized. 17:24:34 INFO - Running cycle 1/1 for dromaeo_css test... 17:24:35 INFO - TEST-INFO | started process 2139 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmplx5dbf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:47 INFO - PROCESS | 2139 | RSS: Main: 193716224 17:24:47 INFO - PROCESS | 2139 | 17:27:51 INFO - PROCESS | 2139 | Cycle 1(1): loaded http://localhost:49571/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49571/tests/dromaeo/cssquery-ext.html) 17:27:52 INFO - PROCESS | 2139 | RSS: Main: 323637248 17:27:52 INFO - PROCESS | 2139 | 17:30:55 INFO - PROCESS | 2139 | Cycle 1(1): loaded http://localhost:49571/tests/dromaeo/cssquery-ext.html (next: http://localhost:49571/tests/dromaeo/cssquery-jquery.html) 17:30:56 INFO - PROCESS | 2139 | RSS: Main: 328069120 17:30:56 INFO - PROCESS | 2139 | 17:33:58 INFO - PROCESS | 2139 | Cycle 1(1): loaded http://localhost:49571/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49571/tests/dromaeo/cssquery-mootools.html) 17:33:59 INFO - PROCESS | 2139 | RSS: Main: 295915520 17:33:59 INFO - PROCESS | 2139 | 17:37:01 INFO - PROCESS | 2139 | Cycle 1(1): loaded http://localhost:49571/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49571/tests/dromaeo/cssquery-prototype.html) 17:37:02 INFO - PROCESS | 2139 | RSS: Main: 292376576 17:37:02 INFO - PROCESS | 2139 | 17:40:05 INFO - PROCESS | 2139 | Cycle 1(1): loaded http://localhost:49571/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49571/tests/dromaeo/cssquery-yui.html) 17:40:06 INFO - PROCESS | 2139 | RSS: Main: 284647424 17:40:06 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | Cycle 1(1): loaded http://localhost:49571/tests/dromaeo/cssquery-yui.html (next: http://localhost:49571/tests/dromaeo/cssquery-dojo.html) 17:43:09 INFO - PROCESS | 2139 | RSS: Main: 343552000 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | __start_tp_report 17:43:09 INFO - PROCESS | 2139 | _x_x_mozilla_page_load 17:43:09 INFO - PROCESS | 2139 | _x_x_mozilla_page_load_details 17:43:09 INFO - PROCESS | 2139 | |i|pagename|runs| 17:43:09 INFO - PROCESS | 2139 | |0;dojo.html;3405.5572775539176;3432.776869503482;3450.1374656335915;3475.3049390121982;3485.7211423086155;5965;6027.819165425039;6030.457258846703;6055.061465472848;6086.482648974837;4827.903441931159;5987.610805297652;6010.939890601093;6036.969815150918;6069.332373438918;5355.036093503178;5372.0330340538685;5446.046941785192;5455.918161227585;5480.835574932738;5299.788008479656;5318.335208098988;5354.759035843377;5483.095289277285;5487.094629386146;5384.165454354562;5395.865103372408;5413.323334583177;5448.972755136218;5473.781048758045;5436.537894278991;5460.31746031746;5476.58925580583;5493.450654934514;5498.257735205756;4990.90018199634;5124;5399.055165346049;5410.783568657249;5459.754311056012;5765.221695071154;5793.826185214408;5803.738831752581;5807.070868660994;6066.211392518944;6371.87256254877;6407.3272306407625;6428.067930150102;6431.3247109053345;6433.9678301608665;6335.3981371769605;6418.614883107029;6420.325865784119;6430.3891130342545;6433.163688720483;6125.1731016313115;6177.042558403455;6183.350748171422;6205.441510264098;6213.409726076015;6321.810345689637;6353.110564520971;6368.490520758328;6510.609363438114;6530.934690653033;6461.450776684034;6469.385408386196;6475.255345635313;6476.967615161988;6480.157579514633;6399.6800159992745;6423.164988551458;6439.613623182624;6442.194725659293;6448;6249.031400132987;6305.779297724557;6415.807525774234;6419.967900160563;6443.194600674916;6360.968195159087;6392.7123279452535;6408.00675895237;6410.455111315498;6416.743330266737;4902.28916807061;4939.75301234944;4942.678725882806;4947.5299846515245;4964.304997300381;4371.825126994948;4463.285874260103;4469.307257375112;4471.195184866755;4474.910501789946;5732.828015159552;5787.681677507693;5820.970895145497;5849;5894.587378883522;6383.936160638427;6414;6415.230172379344;6417.358264173546;6419.101325814389;5854.2389489366105;5875.559333050039;5886.849007118771;5887.646741195542;5896.882062358814;5371.543418809445;5400.270963419888;5476.616636835383;5499.560035197174;5621.8594535136135;6360.268569114611;6391.776287829904;6399.424051835357;6405.775797847053;6513.7068831903625;6353.205849268841;6410.1987251593555;6431.324710905287;6434.195725534309;6458.547901647027;5489.917651235155;6596.505262105457;6598.27418983921;6601.339866013553;6615.338466153538;5787.681677507778;6321.747130114744;6425.9036114457385;6452.22573291208;6470.676466176578;6351.460125889351;6389.48884089281;6395.392437718409;6433.324500927335;6438.678066096582;6390.712417941111;6431.1317972073175;6431.903521447276;6434.581752186093;6457.3865482779065;4362.694611377173;6400.455961243347;6414.069959855888;6418.711157997808;6420.935790642221;5432.701201433801;6034.2155519782145;6088;6219.720112594853;6254.155688982111;6391.520635952229;6393.4885209184085;6416.390442907917;6427.742890284523;6434.967825160844;6268.561200715906;6274.905876411766;6295.496360291074;6296.370362963667;6297.086922396135 17:43:09 INFO - PROCESS | 2139 | |1;ext.html;780.7501599488163;809.2433574607742;829.9751007469774;886.5079880666227;902.7923577577161;29074.854625726868;29269.975550855725;30128.397432051344;30175.24561885954;30194.3961120778;43168;43554.346684799755;45610;45698.54301456984;45747;9532.523373831333;9676.322657413984;9696.369735967139;9705.6603018894;9741.805163896717;7481.289277518627;7641.885371719429;9516;9637.084476974676;9658.420494770302;11527.365994870279;11798.351090690008;11954.581589644364;11962.222455540406;11997.460114294878;11663.708407289801;11720.882791172064;11736.7652646947;11738.119641026957;11739.23694959825;11208.66374008781;11285.379304138269;11288.096952243883;11311.943440282828;11315.886841131565;40543.78368648945;40707.98230044243;40719.59280407188;40813.591864081274;40825;3271.8364081796053;3860.7876566788814;3901.9269700832215;3927.3323534999117;4011.2178125265586;25625.35936601581;25652;25685.871570642215;26797.866010670015;26800.463990720273;2893.7461930961454;3116.5714739013292;3124.3126512167514;3158.921026974344;3209.8555564999633;358304;358496;359186;360193;360507;45982.85042873955;46247;46273;46304.5369546307;46333.768331158804;43291.35062974058;45595.316070258974;48717.75641121772;48734;48751;13987.720245595234;14105.294735263196;14105.85894141066;14126.011179217561;14151.646208844655;12130.575429859913;12146.331951742739;12151.210171338835;12161.391930403444;12161.817572736416;9947.950260248652;10378.792424151474;10578;12435.564755233523;12473.812892806614;6080.57435979486;6239.159447962862;6244.313125556185;6269;6283.497320214372;965.7199412170391;973.2116985241977;979.1481411172325;979.8579206015088;1011.7925825205963;26528.734712652626;29715;29857.552136717968;30177.245568860955;30407.847960760053;924.653255029364;1083.6423980086552;1218.7014181525583;1297.202220634303;1311.5540716156552;13683.452661893612;14065.648358790908;14192.503262385768;14407.567772966828;14435.639109022359;497750;500130;501146;501184;503531;48124.51875481271;49285;52063;58816.70591647014;59300;1295.3264302562802;1303.0683061610805;1304.6607881950572;1467.6771110355705;1470.162007655626;30047;31318.530222047142;31690.36619267648;31805.78504170554;31991.840040800576;48216;48445.03109937852;48521;48554.27168592403;48606.27090593715;453952;454095;454871;455267;456321;27390.315242118308;27835.44329113366;32884.177895552806;34100.65899340975;34481.48277775886;34749.305013900084;43536;47062.29406558836;47249.527504724516;47462;29568.55647165251;30275.788968441015;30599.388012240077;31920.840395797873;32284.83857580697;31002.68997309998;31252.37495250128;31287.217819554695;32090.19774505656;32327;36866.893993180245;37120.44319335158;38194.618053819104;38461.230775384895;38530.80734596421;31381.21546961253;31599.21001974877;31850.362992739552;32024.51963220507;32609.836950815094;24035.519289614458;25153.49693006166;25452.05493148565;25716.099936502134;25935.351616209744 17:43:09 INFO - PROCESS | 2139 | |2;jquery.html;354.9964500354997;392.1881704870917;427.6130102257454;438.2637169555147;459.1047457457956;21374.038168282426;22451.3264602062;22640.20759273426;23100.65349019766;23452.8827355863;23355.883220583873;23433.179838705648;24188.516229675395;24299.028038878423;24535.63196552053;5394.813141108969;6686.465082793365;6869.484788640847;6916.896246556281;6924.549904256232;6728.663566821664;6812.284710105442;6846.178458584976;6849.931500685004;6869.484788640847;6483.254425741029;6750.864982700343;6823.249442561314;6829.590224586515;6863.7597684081065;6673.8665226695675;6696.7656132035145;6710.362515561038;6738.696758645849;6772.322767723237;6303.842403939892;6587.670616469158;6601.405873471411;6605.207375115017;6612.702428390734;28509.572356414465;28691.42617147666;28799.856000720072;28813;29140.125796226148;2992.880284788606;2994.116735563014;3008.819470831757;3015.8492075396143;3016.0951714485786;16322.83677163237;16518;17103.40138095173;18036.819631803646;18063.367782127563;1981.77209620894;2029.4926268432891;2060.6805945078536;2063.7523497180437;2070.7618623858298;128486.3575682125;128502;130652.38695226137;131154;133327;21629;22279.443013924458;22306.442338941655;22392.55214895693;22578.983945722153;36896.52413903468;37294.62705372966;37681;37712;37727.30227139785;9372.718818435458;10754;11112.277701949348;11289.54841806335;11344.659660210042;7518.849623007619;9046.86429703555;9047.230985366186;9057.682981095762;9194.540272986324;10704.78590428187;10835.133189344833;10852.782944341227;10971.543963651584;10976.451177441097;3231.1275955491888;3922.9408059823686;4106.419028673922;4135.1316223593385;4253.553376895381;1052.347544522401;1079.109734469066;1083.1551389915878;1087.853139826129;1127.5827943660734;22471.88764056202;22858.19996300155;22888;22919.54160916772;22935.197268095537;903.5346796399859;1075.876274228458;1091.1303272393714;1117.2570240789785;1121.8429419881222;11319.26424782403;11890.88109118915;12042.458089385998;12086.516539338503;12108.334041627619;724907;738395;738518;745632;758299;131021.34489327684;131397;136811;137743;140222;1268.3024336614824;1271.141979164079;1299.1944994103717;1299.9675008124684;1302.446460254388;18843.24627014959;19577.902110489358;19912.004399780242;20099.39701808948;20108.69836952476;62910.37089629045;62971;64571.70856582936;64651.67674161599;64830.67584662046;80839.74541654774;82847;88167.55916220378;90378;92212;8782.824343513223;11546.711332216762;11807.23252988553;12110.394480276127;12201;8531.95734021326;13176.341182941007;13266.46934122663;13276.668083297995;13721.931390342985;11539.538418463504;11593.478293476644;11671.649850504498;11711.472983715923;12090.032797376012;10969.451527423756;10994.450277486254;11132.499037543168;11215.439228038402;11291.266067705574;11800.350980696145;11908.583199587973;12219.57231496929;12238.020958323135;12298.323592202514;11873.584424545099;11919.46362413676;11942.343171125323;12270;12295.47942671768;11723.237989530653;11740.471678774305;11924.22492538017;12021.75956480883;12180.57367992116 17:43:09 INFO - PROCESS | 2139 | |3;mootools.html;1605.6226786705124;1627.7151498487763;1629.503001584517;1650.7853978982723;1662.2519866060272;10791.514381852816;11266.718332041703;11280.774384512326;11281.323120612758;11304.378259195752;10729.517171727271;11146.331220126798;11210.27133236341;11219.38293393863;11242.943785281062;2372.79831214347;2414.601590737535;2420.9515809683794;2423.297243799305;2429.671994280776;2269.4893648929024;2321.535692861426;2389.354874183969;2390.306811024801;2393.497365553236;2319.2926157522033;2377.2511658827502;2383.0348708772976;2397.2688330059327;2405.6151015837468;2292.667563203326;2349.5066036132434;2364.042562762084;2367.8934447949796;2371.3834403055157;2090.770015298316;2296.2881506732965;2362.9527409451885;2372.5373552157334;2375.798057165143;10738.194635402377;11055.391953442557;11055.889441105566;11086.944565277123;11131.33212007274;5558.388577256498;5718.571107166979;5741.25363702716;5749.568782341299;5755.366909639936;3339.2987472630775;3481.3037392521596;3557.288542291552;3564.1267889367273;3573.678368946781;1165.8600967883908;1167.4221260476033;1176.1237877781023;1206.7586482703493;1216.0271782573993;13651.453941842314;13974.951878609145;14237.64405889861;15251.542453726295;15467.613309667237;4560.817567297271;4684.906301873943;4710.175719249118;4719.693219940693;4720.88197795051;10161.18710503158;11505.597304094485;11535.134864885167;11551.942240288745;11554.017908477874;4912.754362281871;5551.639143455663;5735.369109397963;5741.540676745851;5764.279465066867;4256.34026725858;5257.553107985864;5260.263563101169;5268.92096618551;5340.118880384693;3823.9426408603895;3842.442845787345;3872.1287710264965;3894.2600905827803;3905.7461265017682;867.8568036274071;1005.2309982863115;1007.496251874063;1011.5346940407347;1025.1593693171676;806.435495153395;847.8643417053241;850.2815121222557;872.7207293665966;900.6442455230148;6699.39705426514;6714.966425167843;6904.516683832184;6917.169939607279;6962.582245065312;1002.4837208837455;1011.7217765114506;1019.8317277649105;1049.071571659087;1072.383379556758;4203.831846726097;4270.0605866709275;4319.8451881807005;4377.737335759864;4513.323001549728;11832.875876791692;13187.67030824237;13430.798538021938;13792.793108103386;13843;13000.154989925624;13737.793933091012;13753.243571603458;13772.96702747278;13777.242251676254;9945;10203.336783109075;10204.897951020543;10216.693499195037;10236.641717539851;6050.45545900871;6857.611128877622;6915.515913886181;7013.614251216232;7024.297570242935;10398.324108932895;12018.75962480763;12090.879091208975;12271.877281227073;12328.876711233132;12331.321777301975;12429.316387598768;12462.189957652723;12477.376131193585;12587.559435419716;9298.907010929805;9300.39547429415;9468.715938521855;9509.857352139585;9527.666531671359;10385.792284154426;10397.11624511925;10485.633002844865;10504.78990420172;10507.684769456622;8739.868901966604;8893.73318800437;8944.37389382737;8960.551972401485;8974.551272436483;8561.957190214258;8669.956650216709;8745.562721863755;8785.780355491164;8818.867716984121;7745.109312429143;7970.202979701983;8028.799280017813;8031.678732850854;8038.276555110068;5843.2695913010875;5867.882642347215;5929.318128415288;6047.395260473917;6136.2636483622255;5911.556633252438;5952.226210592596;5975.073863551156;6195.287541932736;6228.408301211378 17:43:09 INFO - PROCESS | 2139 | |4;prototype.html;357.39421680251974;383.8502983836303;385.1507426125394;390.23319177815597;393.6948864629911;4405.770460358052;4989.625778066646;5033.295338652587;5039.44566097729;5081.898362032757;4273.615374616284;4390.429244198258;4628.7685615719165;4938.7530623468865;5005.274235235888;4056.472658554384;4146.792660366969;4185.6651467882575;4204.894877628069;4223.408722778813;3610.9277814443694;3708.8516459341595;3919.8824035278985;4032.717709760318;4173.499180098393;3569.7858128512244;3628.675533430305;3869.8645547405845;4206.852760153395;4242.554531774165;3774.414965680338;4083.121035016716;4113.4035564843225;4237.00430398856;4274.636655884254;3844.9807750961345;3871.4386413969814;3879.4180872869015;3881.844726210948;3902.2585708715255;4045.9190816183805;4462.040661257813;4759.904801903943;4875.7074575525585;4985.2581080406;4724.952750472486;4890.2370160859;4899.779509922062;4907.503211520485;4961.404631444214;5120.670095106718;5325.627206095575;5329.175536965415;5363.597730170253;5386.622936394454;3770.7171962102952;4318.632916202127;4335.848245311399;4351.151525452507;4372.912541749179;4164.0977462004375;4797.2564252540915;5122.595661577035;5276.973615131899;5459.617826752129;3914.8042597869994;3933.370660694275;4171.124063946544;4371.693981421334;4410.977945110318;3931.859760669374;4485.506594274627;4520.660950428731;4527.637788976874;4549.998000879602;4374.059577190919;4551.4765801932535;4821.31169655214;4860.756962151878;4928.408590969107;4442.4669039715445;5176.870578235499;5183.688978661293;5273.515543834369;5456.590755693339;1466.3796504861973;1497.341169885247;1520.6502504424045;1521.6761417566568;1527.4496386168048;1033.89661033896;1091.9781604367868;1127.577158565538;1156.3582211872324;1177.717347836513;4533.478649955231;4611.164134057531;4826.638002149853;4869.537393947569;4905.3623029006;4780.808767649325;5062.392512898402;5204.9219261711105;5570.844217140083;5572.60991730575;3970.344893092665;4143.744454303449;4616.884577885513;4830.782614782343;4844.660873738804;2446.412860913368;2855.9286017849304;2857.5427931530853;2862.2415060009116;2888;4691.7184968901975;4806.855794326176;4959.504049595083;5230.869228269247;5347.171188465794;4728.692634978715;4801.327814106028;5196.558292545176;5666.575006874501;5728.713564321767;5030.641726733761;5485.122380419273;5570.8775506125985;5620.634658747168;5626.971865140648;5206.817761378334;5218.356217791384;5539.14143307788;5566.248556444828;5616.073347897551;5159.845204643867;5199.92200116991;5208.452997557589;5523.502884740232;5526.944730552804;4634.4670362907345;5430.131179011497;5716.314042314949;5755.424457554211;5779.872924779627;4393.132918509685;4452.510223875306;4455.858619673058;4481.602342746615;4483.574060464251;4730.881727956828;5191.636585438983;5214;5546.722663866871;5613.831585052455;5174.136311866833;5367.785288588569;5367.838964831061;5379.579189364723;5456.918146227889;4962.42932062803;5380.43505431924;5389.8922021558565;5407.080796264566;5412.648177868426;4419.889502762328;4927.926081108858;5268.9736551317;5290.635225938693;5334.293026231724;5340.385855626652;5388.655934668432;5408.0806262936185;5422.213779002102;5436.320459942507;4612.469565999953;4653.9761252524395;5257.710825904612;5271.841844744664;5272.420033796203 17:43:09 INFO - PROCESS | 2139 | |5;yui.html;759.5670467833337;766.8236305649697;788.6805843633327;860.9182127697868;878.5519385113593;14031.649208769773;15104.244787760623;15394.384224631001;15933.920330398332;15936.362545498167;14744.778828317583;14891.329890154942;14893.180875051868;14896.25518724065;14899.627509312273;974.2206235011997;988.9950550247239;1007.4660429972126;1011.7824667696444;1022.7187523431041;1023.8668973033496;1024.068098030792;1024.1653052762013;1024.810410074135;1026.743314171457;1069.4599227390197;1069.7806949575318;1070.202698989254;1071.8338657508098;1072.9141668666489;1062.2988827373936;1062.6865074802952;1063.0432610650398;1063.415121683079;1068.1668298726966;793.6309616028517;794.5788731972063;796.9920300796975;799.7280924485644;801.3348920396115;15878.444254451038;16299.592510187222;16406.507804765875;16502.42241521553;16526.091064991422;14317.85682143186;14359.425622975068;14553.563393098224;14568.927155364261;14597.708045839025;11718.12114091438;11798.41007949599;11804.763904721944;11906.940465297617;12311.138220324581;3640.199156185635;3706.9073273168115;3914.5889681583594;3923.058465968176;3927.7839718815453;91271;108185.37721934177;119544.40227798805;121680;123557.38221308925;10658.307210031324;10673.092787113183;10950.233483656068;11329.886701133048;11501.367424791717;23373.71444570565;23740.88129559341;23761;23762.881185594306;23816.285511434686;7867.567283799446;7906.90467026227;7925.365970722329;7997.240262175084;8061.838763224702;4351.129774045204;4406.559344065632;4537.1833070047705;4662.0209755950955;4675.228587283128;2823.957640635392;3505.4391297392804;3507.4212754895666;3509.701675357572;3527.4532447470683;574.913762935563;593.5133190783516;601.8044135655929;624.893768059431;625.0967352176062;682.5563383800569;695.151914664109;714.5605452646585;716.451914285572;741.6217728958162;9244.907550924405;10426.113780328606;10465.9476702616;10547;10673.199510036779;591.340655169483;601.5680542504998;609.4423602403751;620.6803496199461;642.1267076775581;4297.355396690521;4770.833020844323;5057.165567681365;5057.747112644353;5073;77295.61352193315;94040.52979735195;98237.50881245547;98700;99383;80054;89190;90552;92099.53950230205;92279.53860230657;51654.48345516497;65909;67651;72163.27836721565;76792;9792.755181120529;10265.281430299807;11179.217454778413;11331.376774277494;11342.489587968395;47311.29033064438;47645.52354476411;48881;49442.25836612382;50047.74976125096;56729.86540269254;61198.38801612105;61641.07538387017;62417.43956401126;66829.66585167237;13027.609171724864;13137.73724525485;13317.7744121957;13839.930800345934;13905.550223928245;14651.926740366656;15000.474983375529;15012.849871501145;15115.773263401277;15564.766528502309;13414.262215578237;13903.652408689459;14281.571552853431;14788.038777479429;14848.109113453227;13162.341882905625;13283.535076272285;14122.858771412566;14127.293635318398;14671.55985320442;13052.738945221232;13761.38073786662;13952.511662092184;14028.78956815669;14236.501722439665;9271.953640231755;9784.755381115529;9910.851337230091;10150.23873209527;10158.984101589782;9296.117107058677;11367.374794386387;11477.655670329903;11543.076553875502;11544.365059921785 17:43:09 INFO - PROCESS | 2139 | __end_tp_report 17:43:09 INFO - PROCESS | 2139 | __start_cc_report 17:43:09 INFO - PROCESS | 2139 | _x_x_mozilla_cycle_collect,1357 17:43:09 INFO - PROCESS | 2139 | __end_cc_report 17:43:09 INFO - PROCESS | 2139 | __startTimestamp1495672989927__endTimestamp 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | ------- Summary: start ------- 17:43:09 INFO - PROCESS | 2139 | Number of tests: 6 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | [#0] dojo.html Cycles:165 Average:5915.20 Median:6249.03 stddev:697.73 (11.2%) stddev-sans-first:671.52 17:43:09 INFO - PROCESS | 2139 | Values: 3405.6 3432.8 3450.1 3475.3 3485.7 5965.0 6027.8 6030.5 6055.1 6086.5 4827.9 5987.6 6010.9 6037.0 6069.3 5355.0 5372.0 5446.0 5455.9 5480.8 5299.8 5318.3 5354.8 5483.1 5487.1 5384.2 5395.9 5413.3 5449.0 5473.8 5436.5 5460.3 5476.6 5493.5 5498.3 4990.9 5124.0 5399.1 5410.8 5459.8 5765.2 5793.8 5803.7 5807.1 6066.2 6371.9 6407.3 6428.1 6431.3 6434.0 6335.4 6418.6 6420.3 6430.4 6433.2 6125.2 6177.0 6183.4 6205.4 6213.4 6321.8 6353.1 6368.5 6510.6 6530.9 6461.5 6469.4 6475.3 6477.0 6480.2 6399.7 6423.2 6439.6 6442.2 6448.0 6249.0 6305.8 6415.8 6420.0 6443.2 6361.0 6392.7 6408.0 6410.5 6416.7 4902.3 4939.8 4942.7 4947.5 4964.3 4371.8 4463.3 4469.3 4471.2 4474.9 5732.8 5787.7 5821.0 5849.0 5894.6 6383.9 6414.0 6415.2 6417.4 6419.1 5854.2 5875.6 5886.8 5887.6 5896.9 5371.5 5400.3 5476.6 5499.6 5621.9 6360.3 6391.8 6399.4 6405.8 6513.7 6353.2 6410.2 6431.3 6434.2 6458.5 5489.9 6596.5 6598.3 6601.3 6615.3 5787.7 6321.7 6425.9 6452.2 6470.7 6351.5 6389.5 6395.4 6433.3 6438.7 6390.7 6431.1 6431.9 6434.6 6457.4 4362.7 6400.5 6414.1 6418.7 6420.9 5432.7 6034.2 6088.0 6219.7 6254.2 6391.5 6393.5 6416.4 6427.7 6435.0 6268.6 6274.9 6295.5 6296.4 6297.1 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | [#1] ext.html Cycles:180 Average:57430.39 Median:17940.84 stddev:117475.18 (654.8%) stddev-sans-first:117727.73 17:43:09 INFO - PROCESS | 2139 | Values: 780.8 809.2 830.0 886.5 902.8 29074.9 29270.0 30128.4 30175.2 30194.4 43168.0 43554.3 45610.0 45698.5 45747.0 9532.5 9676.3 9696.4 9705.7 9741.8 7481.3 7641.9 9516.0 9637.1 9658.4 11527.4 11798.4 11954.6 11962.2 11997.5 11663.7 11720.9 11736.8 11738.1 11739.2 11208.7 11285.4 11288.1 11311.9 11315.9 40543.8 40708.0 40719.6 40813.6 40825.0 3271.8 3860.8 3901.9 3927.3 4011.2 25625.4 25652.0 25685.9 26797.9 26800.5 2893.7 3116.6 3124.3 3158.9 3209.9 358304.0 358496.0 359186.0 360193.0 360507.0 45982.9 46247.0 46273.0 46304.5 46333.8 43291.4 45595.3 48717.8 48734.0 48751.0 13987.7 14105.3 14105.9 14126.0 14151.6 12130.6 12146.3 12151.2 12161.4 12161.8 9948.0 10378.8 10578.0 12435.6 12473.8 6080.6 6239.2 6244.3 6269.0 6283.5 965.7 973.2 979.1 979.9 1011.8 26528.7 29715.0 29857.6 30177.2 30407.8 924.7 1083.6 1218.7 1297.2 1311.6 13683.5 14065.6 14192.5 14407.6 14435.6 497750.0 500130.0 501146.0 501184.0 503531.0 48124.5 49285.0 52063.0 58816.7 59300.0 1295.3 1303.1 1304.7 1467.7 1470.2 30047.0 31318.5 31690.4 31805.8 31991.8 48216.0 48445.0 48521.0 48554.3 48606.3 453952.0 454095.0 454871.0 455267.0 456321.0 27390.3 27835.4 32884.2 34100.7 34481.5 34749.3 43536.0 47062.3 47249.5 47462.0 29568.6 30275.8 30599.4 31920.8 32284.8 31002.7 31252.4 31287.2 32090.2 32327.0 36866.9 37120.4 38194.6 38461.2 38530.8 31381.2 31599.2 31850.4 32024.5 32609.8 24035.5 25153.5 25452.1 25716.1 25935.4 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | [#2] jquery.html Cycles:180 Average:42327.72 Median:22421.94 stddev:122664.53 (547.1%) stddev-sans-first:122968.15 17:43:09 INFO - PROCESS | 2139 | Values: 355.0 392.2 427.6 438.3 459.1 21374.0 22451.3 22640.2 23100.7 23452.9 23355.9 23433.2 24188.5 24299.0 24535.6 5394.8 6686.5 6869.5 6916.9 6924.5 6728.7 6812.3 6846.2 6849.9 6869.5 6483.3 6750.9 6823.2 6829.6 6863.8 6673.9 6696.8 6710.4 6738.7 6772.3 6303.8 6587.7 6601.4 6605.2 6612.7 28509.6 28691.4 28799.9 28813.0 29140.1 2992.9 2994.1 3008.8 3015.8 3016.1 16322.8 16518.0 17103.4 18036.8 18063.4 1981.8 2029.5 2060.7 2063.8 2070.8 128486.4 128502.0 130652.4 131154.0 133327.0 21629.0 22279.4 22306.4 22392.6 22579.0 36896.5 37294.6 37681.0 37712.0 37727.3 9372.7 10754.0 11112.3 11289.5 11344.7 7518.8 9046.9 9047.2 9057.7 9194.5 10704.8 10835.1 10852.8 10971.5 10976.5 3231.1 3922.9 4106.4 4135.1 4253.6 1052.3 1079.1 1083.2 1087.9 1127.6 22471.9 22858.2 22888.0 22919.5 22935.2 903.5 1075.9 1091.1 1117.3 1121.8 11319.3 11890.9 12042.5 12086.5 12108.3 724907.0 738395.0 738518.0 745632.0 758299.0 131021.3 131397.0 136811.0 137743.0 140222.0 1268.3 1271.1 1299.2 1300.0 1302.4 18843.2 19577.9 19912.0 20099.4 20108.7 62910.4 62971.0 64571.7 64651.7 64830.7 80839.7 82847.0 88167.6 90378.0 92212.0 8782.8 11546.7 11807.2 12110.4 12201.0 8532.0 13176.3 13266.5 13276.7 13721.9 11539.5 11593.5 11671.6 11711.5 12090.0 10969.5 10994.5 11132.5 11215.4 11291.3 11800.4 11908.6 12219.6 12238.0 12298.3 11873.6 11919.5 11942.3 12270.0 12295.5 11723.2 11740.5 11924.2 12021.8 12180.6 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | [#3] mootools.html Cycles:180 Average:6712.02 Median:2391.90 stddev:4159.65 (173.9%) stddev-sans-first:4153.62 17:43:09 INFO - PROCESS | 2139 | Values: 1605.6 1627.7 1629.5 1650.8 1662.3 10791.5 11266.7 11280.8 11281.3 11304.4 10729.5 11146.3 11210.3 11219.4 11242.9 2372.8 2414.6 2421.0 2423.3 2429.7 2269.5 2321.5 2389.4 2390.3 2393.5 2319.3 2377.3 2383.0 2397.3 2405.6 2292.7 2349.5 2364.0 2367.9 2371.4 2090.8 2296.3 2363.0 2372.5 2375.8 10738.2 11055.4 11055.9 11086.9 11131.3 5558.4 5718.6 5741.3 5749.6 5755.4 3339.3 3481.3 3557.3 3564.1 3573.7 1165.9 1167.4 1176.1 1206.8 1216.0 13651.5 13975.0 14237.6 15251.5 15467.6 4560.8 4684.9 4710.2 4719.7 4720.9 10161.2 11505.6 11535.1 11551.9 11554.0 4912.8 5551.6 5735.4 5741.5 5764.3 4256.3 5257.6 5260.3 5268.9 5340.1 3823.9 3842.4 3872.1 3894.3 3905.7 867.9 1005.2 1007.5 1011.5 1025.2 806.4 847.9 850.3 872.7 900.6 6699.4 6715.0 6904.5 6917.2 6962.6 1002.5 1011.7 1019.8 1049.1 1072.4 4203.8 4270.1 4319.8 4377.7 4513.3 11832.9 13187.7 13430.8 13792.8 13843.0 13000.2 13737.8 13753.2 13773.0 13777.2 9945.0 10203.3 10204.9 10216.7 10236.6 6050.5 6857.6 6915.5 7013.6 7024.3 10398.3 12018.8 12090.9 12271.9 12328.9 12331.3 12429.3 12462.2 12477.4 12587.6 9298.9 9300.4 9468.7 9509.9 9527.7 10385.8 10397.1 10485.6 10504.8 10507.7 8739.9 8893.7 8944.4 8960.6 8974.6 8562.0 8670.0 8745.6 8785.8 8818.9 7745.1 7970.2 8028.8 8031.7 8038.3 5843.3 5867.9 5929.3 6047.4 6136.3 5911.6 5952.2 5975.1 6195.3 6228.4 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | [#4] prototype.html Cycles:180 Average:4440.85 Median:4799.29 stddev:1224.15 (25.5%) stddev-sans-first:1188.60 17:43:09 INFO - PROCESS | 2139 | Values: 357.4 383.9 385.2 390.2 393.7 4405.8 4989.6 5033.3 5039.4 5081.9 4273.6 4390.4 4628.8 4938.8 5005.3 4056.5 4146.8 4185.7 4204.9 4223.4 3610.9 3708.9 3919.9 4032.7 4173.5 3569.8 3628.7 3869.9 4206.9 4242.6 3774.4 4083.1 4113.4 4237.0 4274.6 3845.0 3871.4 3879.4 3881.8 3902.3 4045.9 4462.0 4759.9 4875.7 4985.3 4725.0 4890.2 4899.8 4907.5 4961.4 5120.7 5325.6 5329.2 5363.6 5386.6 3770.7 4318.6 4335.8 4351.2 4372.9 4164.1 4797.3 5122.6 5277.0 5459.6 3914.8 3933.4 4171.1 4371.7 4411.0 3931.9 4485.5 4520.7 4527.6 4550.0 4374.1 4551.5 4821.3 4860.8 4928.4 4442.5 5176.9 5183.7 5273.5 5456.6 1466.4 1497.3 1520.7 1521.7 1527.4 1033.9 1092.0 1127.6 1156.4 1177.7 4533.5 4611.2 4826.6 4869.5 4905.4 4780.8 5062.4 5204.9 5570.8 5572.6 3970.3 4143.7 4616.9 4830.8 4844.7 2446.4 2855.9 2857.5 2862.2 2888.0 4691.7 4806.9 4959.5 5230.9 5347.2 4728.7 4801.3 5196.6 5666.6 5728.7 5030.6 5485.1 5570.9 5620.6 5627.0 5206.8 5218.4 5539.1 5566.2 5616.1 5159.8 5199.9 5208.5 5523.5 5526.9 4634.5 5430.1 5716.3 5755.4 5779.9 4393.1 4452.5 4455.9 4481.6 4483.6 4730.9 5191.6 5214.0 5546.7 5613.8 5174.1 5367.8 5367.8 5379.6 5456.9 4962.4 5380.4 5389.9 5407.1 5412.6 4419.9 4927.9 5269.0 5290.6 5334.3 5340.4 5388.7 5408.1 5422.2 5436.3 4612.5 4654.0 5257.7 5271.8 5272.4 17:43:09 INFO - PROCESS | 2139 | 17:43:09 INFO - PROCESS | 2139 | [#5] yui.html Cycles:180 Average:20129.57 Median:19949.90 stddev:28505.01 (142.9%) stddev-sans-first:28547.87 17:43:09 INFO - PROCESS | 2139 | Values: 759.6 766.8 788.7 860.9 878.6 14031.6 15104.2 15394.4 15933.9 15936.4 14744.8 14891.3 14893.2 14896.3 14899.6 974.2 989.0 1007.5 1011.8 1022.7 1023.9 1024.1 1024.2 1024.8 1026.7 1069.5 1069.8 1070.2 1071.8 1072.9 1062.3 1062.7 1063.0 1063.4 1068.2 793.6 794.6 797.0 799.7 801.3 15878.4 16299.6 16406.5 16502.4 16526.1 14317.9 14359.4 14553.6 14568.9 14597.7 11718.1 11798.4 11804.8 11906.9 12311.1 3640.2 3706.9 3914.6 3923.1 3927.8 91271.0 108185.4 119544.4 121680.0 123557.4 10658.3 10673.1 10950.2 11329.9 11501.4 23373.7 23740.9 23761.0 23762.9 23816.3 7867.6 7906.9 7925.4 7997.2 8061.8 4351.1 4406.6 4537.2 4662.0 4675.2 2824.0 3505.4 3507.4 3509.7 3527.5 574.9 593.5 601.8 624.9 625.1 682.6 695.2 714.6 716.5 741.6 9244.9 10426.1 10465.9 10547.0 10673.2 591.3 601.6 609.4 620.7 642.1 4297.4 4770.8 5057.2 5057.7 5073.0 77295.6 94040.5 98237.5 98700.0 99383.0 80054.0 89190.0 90552.0 92099.5 92279.5 51654.5 65909.0 67651.0 72163.3 76792.0 9792.8 10265.3 11179.2 11331.4 11342.5 47311.3 47645.5 48881.0 49442.3 50047.7 56729.9 61198.4 61641.1 62417.4 66829.7 13027.6 13137.7 13317.8 13839.9 13905.6 14651.9 15000.5 15012.8 15115.8 15564.8 13414.3 13903.7 14281.6 14788.0 14848.1 13162.3 13283.5 14122.9 14127.3 14671.6 13052.7 13761.4 13952.5 14028.8 14236.5 9272.0 9784.8 9910.9 10150.2 10159.0 9296.1 11367.4 11477.7 11543.1 11544.4 17:43:09 INFO - PROCESS | 2139 | -------- Summary: end -------- 17:43:09 INFO - PROCESS | 2139 | 17:43:10 INFO - TEST-INFO | 2139: exit 0 17:43:10 INFO - TEST-OK | dromaeo_css | took 1118705ms 17:43:10 INFO - TEST-START | kraken 17:43:10 INFO - operating with platform_type : mac_ 17:43:10 INFO - Initialising browser for kraken test... 17:43:10 INFO - TEST-INFO | started process 2254 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmp1FiAoQ/profile http://localhost:49571/getInfo.html) 17:43:12 INFO - PROCESS | 2254 | __metrics Screen width/height:1600/1200 17:43:12 INFO - PROCESS | 2254 | colorDepth:24 17:43:12 INFO - PROCESS | 2254 | Browser inner width/height: 1024/689 17:43:12 INFO - PROCESS | 2254 | __metrics 17:43:12 INFO - PROCESS | 2254 | 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" 17:43:12 INFO - PROCESS | 2254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:43:13 INFO - TEST-INFO | 2254: exit 0 17:43:13 INFO - Browser initialized. 17:43:13 INFO - Running cycle 1/1 for kraken test... 17:43:13 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmp1FiAoQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:43:25 INFO - PROCESS | 2256 | RSS: Main: 193851392 17:43:25 INFO - PROCESS | 2256 | 17:43:28 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49571/tests/kraken/driver.html?testName=audio-beat-detection) 17:43:29 INFO - PROCESS | 2256 | RSS: Main: 244580352 17:43:29 INFO - PROCESS | 2256 | 17:43:31 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49571/tests/kraken/driver.html?testName=audio-dft) 17:43:32 INFO - PROCESS | 2256 | RSS: Main: 258715648 17:43:32 INFO - PROCESS | 2256 | 17:43:35 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49571/tests/kraken/driver.html?testName=audio-fft) 17:43:36 INFO - PROCESS | 2256 | RSS: Main: 356450304 17:43:36 INFO - PROCESS | 2256 | 17:43:38 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49571/tests/kraken/driver.html?testName=audio-oscillator) 17:43:39 INFO - PROCESS | 2256 | RSS: Main: 256688128 17:43:39 INFO - PROCESS | 2256 | 17:43:41 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49571/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:43:41 INFO - PROCESS | 2256 | RSS: Main: 259702784 17:43:41 INFO - PROCESS | 2256 | 17:43:44 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49571/tests/kraken/driver.html?testName=imaging-darkroom) 17:43:45 INFO - PROCESS | 2256 | RSS: Main: 299925504 17:43:45 INFO - PROCESS | 2256 | 17:43:48 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49571/tests/kraken/driver.html?testName=imaging-desaturate) 17:43:48 INFO - PROCESS | 2256 | RSS: Main: 300986368 17:43:48 INFO - PROCESS | 2256 | 17:43:51 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49571/tests/kraken/driver.html?testName=json-parse-financial) 17:43:52 INFO - PROCESS | 2256 | RSS: Main: 298975232 17:43:52 INFO - PROCESS | 2256 | 17:43:53 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49571/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:43:54 INFO - PROCESS | 2256 | RSS: Main: 276393984 17:43:54 INFO - PROCESS | 2256 | 17:43:56 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:43:57 INFO - PROCESS | 2256 | RSS: Main: 278556672 17:43:57 INFO - PROCESS | 2256 | 17:43:59 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:43:59 INFO - PROCESS | 2256 | RSS: Main: 282071040 17:43:59 INFO - PROCESS | 2256 | 17:44:02 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:44:03 INFO - PROCESS | 2256 | RSS: Main: 297865216 17:44:03 INFO - PROCESS | 2256 | 17:44:06 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:44:07 INFO - PROCESS | 2256 | RSS: Main: 292130816 17:44:07 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:49571/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49571/tests/kraken/driver.html?testName=ai-astar) 17:44:09 INFO - PROCESS | 2256 | RSS: Main: 336236544 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | __start_tp_report 17:44:09 INFO - PROCESS | 2256 | _x_x_mozilla_page_load 17:44:09 INFO - PROCESS | 2256 | _x_x_mozilla_page_load_details 17:44:09 INFO - PROCESS | 2256 | |i|pagename|runs| 17:44:09 INFO - PROCESS | 2256 | |0;ai-astar;105;116;115;117;116;117;115;117;115;114 17:44:09 INFO - PROCESS | 2256 | |1;audio-beat-detection;143;107;106;142;107;105;110;107;127;107 17:44:09 INFO - PROCESS | 2256 | |2;audio-dft;185;184;184;186;186;185;185;252;239;187 17:44:09 INFO - PROCESS | 2256 | |3;audio-fft;84;59;58;88;60;63;63;78;98;82 17:44:09 INFO - PROCESS | 2256 | |4;audio-oscillator;90;76;89;69;69;69;81;69;69;84 17:44:09 INFO - PROCESS | 2256 | |5;imaging-gaussian-blur;86;86;102;135;86;86;87;87;86;86 17:44:09 INFO - PROCESS | 2256 | |6;imaging-darkroom;94;93;94;93;93;94;95;108;105;94 17:44:09 INFO - PROCESS | 2256 | |7;imaging-desaturate;85;85;83;86;85;83;86;83;86;85 17:44:09 INFO - PROCESS | 2256 | |8;json-parse-financial;57;57;57;57;64;63;62;62;62;63 17:44:09 INFO - PROCESS | 2256 | |9;json-stringify-tinderbox;53;64;63;65;79;83;83;84;60;57 17:44:09 INFO - PROCESS | 2256 | |10;stanford-crypto-aes;86;85;80;77;84;70;104;100;100;78 17:44:09 INFO - PROCESS | 2256 | |11;stanford-crypto-ccm;140;137;136;135;132;153;149;147;178;157 17:44:09 INFO - PROCESS | 2256 | |12;stanford-crypto-pbkdf2;164;163;173;168;164;165;215;222;171;161 17:44:09 INFO - PROCESS | 2256 | |13;stanford-crypto-sha256-iterative;65;79;84;81;82;70;66;64;63;64 17:44:09 INFO - PROCESS | 2256 | __end_tp_report 17:44:09 INFO - PROCESS | 2256 | __start_cc_report 17:44:09 INFO - PROCESS | 2256 | _x_x_mozilla_cycle_collect,2106 17:44:09 INFO - PROCESS | 2256 | __end_cc_report 17:44:09 INFO - PROCESS | 2256 | __startTimestamp1495673049316__endTimestamp 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | ------- Summary: start ------- 17:44:09 INFO - PROCESS | 2256 | Number of tests: 14 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.00 stddev:3.56 (3.1%) stddev-sans-first:1.09 17:44:09 INFO - PROCESS | 2256 | Values: 105.0 116.0 115.0 117.0 116.0 117.0 115.0 117.0 115.0 114.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#1] audio-beat-detection Cycles:10 Average:116.10 Median:108.50 stddev:15.30 (14.1%) stddev-sans-first:12.76 17:44:09 INFO - PROCESS | 2256 | Values: 143.0 107.0 106.0 142.0 107.0 105.0 110.0 107.0 127.0 107.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#2] audio-dft Cycles:10 Average:197.30 Median:186.00 stddev:25.60 (13.8%) stddev-sans-first:26.77 17:44:09 INFO - PROCESS | 2256 | Values: 185.0 184.0 184.0 186.0 186.0 185.0 185.0 252.0 239.0 187.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#3] audio-fft Cycles:10 Average:73.30 Median:80.00 stddev:14.40 (18.0%) stddev-sans-first:14.74 17:44:09 INFO - PROCESS | 2256 | Values: 84.0 59.0 58.0 88.0 60.0 63.0 63.0 78.0 98.0 82.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.50 stddev:8.80 (11.2%) stddev-sans-first:7.86 17:44:09 INFO - PROCESS | 2256 | Values: 90.0 76.0 89.0 69.0 69.0 69.0 81.0 69.0 69.0 84.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:86.00 stddev:15.67 (18.2%) stddev-sans-first:16.43 17:44:09 INFO - PROCESS | 2256 | Values: 86.0 86.0 102.0 135.0 86.0 86.0 87.0 87.0 86.0 86.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:94.00 stddev:5.46 (5.8%) stddev-sans-first:5.73 17:44:09 INFO - PROCESS | 2256 | Values: 94.0 93.0 94.0 93.0 93.0 94.0 95.0 108.0 105.0 94.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.25 (1.5%) stddev-sans-first:1.32 17:44:09 INFO - PROCESS | 2256 | Values: 85.0 85.0 83.0 86.0 85.0 83.0 86.0 83.0 86.0 85.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:62.00 stddev:2.99 (4.8%) stddev-sans-first:2.91 17:44:09 INFO - PROCESS | 2256 | Values: 57.0 57.0 57.0 57.0 64.0 63.0 62.0 62.0 62.0 63.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:72.00 stddev:11.90 (16.5%) stddev-sans-first:11.11 17:44:09 INFO - PROCESS | 2256 | Values: 53.0 64.0 63.0 65.0 79.0 83.0 83.0 84.0 60.0 57.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:79.00 stddev:11.34 (14.3%) stddev-sans-first:12.02 17:44:09 INFO - PROCESS | 2256 | Values: 86.0 85.0 80.0 77.0 84.0 70.0 104.0 100.0 100.0 78.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:148.00 stddev:13.89 (9.4%) stddev-sans-first:14.54 17:44:09 INFO - PROCESS | 2256 | Values: 140.0 137.0 136.0 135.0 132.0 153.0 149.0 147.0 178.0 157.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.60 Median:169.50 stddev:22.45 (13.2%) stddev-sans-first:23.34 17:44:09 INFO - PROCESS | 2256 | Values: 164.0 163.0 173.0 168.0 164.0 165.0 215.0 222.0 171.0 161.0 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - PROCESS | 2256 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:74.50 stddev:8.64 (11.6%) stddev-sans-first:8.80 17:44:09 INFO - PROCESS | 2256 | Values: 65.0 79.0 84.0 81.0 82.0 70.0 66.0 64.0 63.0 64.0 17:44:09 INFO - PROCESS | 2256 | -------- Summary: end -------- 17:44:09 INFO - PROCESS | 2256 | 17:44:09 INFO - TEST-INFO | 2256: exit 0 17:44:09 INFO - TEST-OK | kraken | took 59561ms 17:44:09 INFO - SUITE-END | took 1178s 17:44:10 INFO - Completed test suite (00:19:38) 17:44:10 INFO - javascript benchmark 17:44:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7732.308252674868, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3405.5572775539176, 3432.776869503482, 3450.1374656335915, 3475.3049390121982, 3485.7211423086155, 5965.0, 6027.819165425039, 6030.457258846703, 6055.061465472848, 6086.482648974837, 4827.903441931159, 5987.610805297652, 6010.939890601093, 6036.969815150918, 6069.332373438918, 5355.036093503178, 5372.0330340538685, 5446.046941785192, 5455.918161227585, 5480.835574932738, 5299.788008479656, 5318.335208098988, 5354.759035843377, 5483.095289277285, 5487.094629386146, 5384.165454354562, 5395.865103372408, 5413.323334583177, 5448.972755136218, 5473.781048758045, 5436.537894278991, 5460.31746031746, 5476.58925580583, 5493.450654934514, 5498.257735205756, 4990.90018199634, 5124.0, 5399.055165346049, 5410.783568657249, 5459.754311056012, 5765.221695071154, 5793.826185214408, 5803.738831752581, 5807.070868660994, 6066.211392518944, 6371.87256254877, 6407.3272306407625, 6428.067930150102, 6431.3247109053345, 6433.9678301608665, 6335.3981371769605, 6418.614883107029, 6420.325865784119, 6430.3891130342545, 6433.163688720483, 6125.1731016313115, 6177.042558403455, 6183.350748171422, 6205.441510264098, 6213.409726076015, 6321.810345689637, 6353.110564520971, 6368.490520758328, 6510.609363438114, 6530.934690653033, 6461.450776684034, 6469.385408386196, 6475.255345635313, 6476.967615161988, 6480.157579514633, 6399.6800159992745, 6423.164988551458, 6439.613623182624, 6442.194725659293, 6448.0, 6249.031400132987, 6305.779297724557, 6415.807525774234, 6419.967900160563, 6443.194600674916, 6360.968195159087, 6392.7123279452535, 6408.00675895237, 6410.455111315498, 6416.743330266737, 4902.28916807061, 4939.75301234944, 4942.678725882806, 4947.5299846515245, 4964.304997300381, 4371.825126994948, 4463.285874260103, 4469.307257375112, 4471.195184866755, 4474.910501789946, 5732.828015159552, 5787.681677507693, 5820.970895145497, 5849.0, 5894.587378883522, 6383.936160638427, 6414.0, 6415.230172379344, 6417.358264173546, 6419.101325814389, 5854.2389489366105, 5875.559333050039, 5886.849007118771, 5887.646741195542, 5896.882062358814, 5371.543418809445, 5400.270963419888, 5476.616636835383, 5499.560035197174, 5621.8594535136135, 6360.268569114611, 6391.776287829904, 6399.424051835357, 6405.775797847053, 6513.7068831903625, 6353.205849268841, 6410.1987251593555, 6431.324710905287, 6434.195725534309, 6458.547901647027, 5489.917651235155, 6596.505262105457, 6598.27418983921, 6601.339866013553, 6615.338466153538, 5787.681677507778, 6321.747130114744, 6425.9036114457385, 6452.22573291208, 6470.676466176578, 6351.460125889351, 6389.48884089281, 6395.392437718409, 6433.324500927335, 6438.678066096582, 6390.712417941111, 6431.1317972073175, 6431.903521447276, 6434.581752186093, 6457.3865482779065, 4362.694611377173, 6400.455961243347, 6414.069959855888, 6418.711157997808, 6420.935790642221, 5432.701201433801, 6034.2155519782145, 6088.0, 6219.720112594853, 6254.155688982111, 6391.520635952229, 6393.4885209184085, 6416.390442907917, 6427.742890284523, 6434.967825160844, 6268.561200715906, 6274.905876411766, 6295.496360291074, 6296.370362963667, 6297.086922396135], "value": 5870.389033088947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.7501599488163, 809.2433574607742, 829.9751007469774, 886.5079880666227, 902.7923577577161, 29074.854625726868, 29269.975550855725, 30128.397432051344, 30175.24561885954, 30194.3961120778, 43168.0, 43554.346684799755, 45610.0, 45698.54301456984, 45747.0, 9532.523373831333, 9676.322657413984, 9696.369735967139, 9705.6603018894, 9741.805163896717, 7481.289277518627, 7641.885371719429, 9516.0, 9637.084476974676, 9658.420494770302, 11527.365994870279, 11798.351090690008, 11954.581589644364, 11962.222455540406, 11997.460114294878, 11663.708407289801, 11720.882791172064, 11736.7652646947, 11738.119641026957, 11739.23694959825, 11208.66374008781, 11285.379304138269, 11288.096952243883, 11311.943440282828, 11315.886841131565, 40543.78368648945, 40707.98230044243, 40719.59280407188, 40813.591864081274, 40825.0, 3271.8364081796053, 3860.7876566788814, 3901.9269700832215, 3927.3323534999117, 4011.2178125265586, 25625.35936601581, 25652.0, 25685.871570642215, 26797.866010670015, 26800.463990720273, 2893.7461930961454, 3116.5714739013292, 3124.3126512167514, 3158.921026974344, 3209.8555564999633, 358304.0, 358496.0, 359186.0, 360193.0, 360507.0, 45982.85042873955, 46247.0, 46273.0, 46304.5369546307, 46333.768331158804, 43291.35062974058, 45595.316070258974, 48717.75641121772, 48734.0, 48751.0, 13987.720245595234, 14105.294735263196, 14105.85894141066, 14126.011179217561, 14151.646208844655, 12130.575429859913, 12146.331951742739, 12151.210171338835, 12161.391930403444, 12161.817572736416, 9947.950260248652, 10378.792424151474, 10578.0, 12435.564755233523, 12473.812892806614, 6080.57435979486, 6239.159447962862, 6244.313125556185, 6269.0, 6283.497320214372, 965.7199412170391, 973.2116985241977, 979.1481411172325, 979.8579206015088, 1011.7925825205963, 26528.734712652626, 29715.0, 29857.552136717968, 30177.245568860955, 30407.847960760053, 924.653255029364, 1083.6423980086552, 1218.7014181525583, 1297.202220634303, 1311.5540716156552, 13683.452661893612, 14065.648358790908, 14192.503262385768, 14407.567772966828, 14435.639109022359, 497750.0, 500130.0, 501146.0, 501184.0, 503531.0, 48124.51875481271, 49285.0, 52063.0, 58816.70591647014, 59300.0, 1295.3264302562802, 1303.0683061610805, 1304.6607881950572, 1467.6771110355705, 1470.162007655626, 30047.0, 31318.530222047142, 31690.36619267648, 31805.78504170554, 31991.840040800576, 48216.0, 48445.03109937852, 48521.0, 48554.27168592403, 48606.27090593715, 453952.0, 454095.0, 454871.0, 455267.0, 456321.0, 27390.315242118308, 27835.44329113366, 32884.177895552806, 34100.65899340975, 34481.48277775886, 34749.305013900084, 43536.0, 47062.29406558836, 47249.527504724516, 47462.0, 29568.55647165251, 30275.788968441015, 30599.388012240077, 31920.840395797873, 32284.83857580697, 31002.68997309998, 31252.37495250128, 31287.217819554695, 32090.19774505656, 32327.0, 36866.893993180245, 37120.44319335158, 38194.618053819104, 38461.230775384895, 38530.80734596421, 31381.21546961253, 31599.21001974877, 31850.362992739552, 32024.51963220507, 32609.836950815094, 24035.519289614458, 25153.49693006166, 25452.05493148565, 25716.099936502134, 25935.351616209744], "value": 18880.598064759117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.9964500354997, 392.1881704870917, 427.6130102257454, 438.2637169555147, 459.1047457457956, 21374.038168282426, 22451.3264602062, 22640.20759273426, 23100.65349019766, 23452.8827355863, 23355.883220583873, 23433.179838705648, 24188.516229675395, 24299.028038878423, 24535.63196552053, 5394.813141108969, 6686.465082793365, 6869.484788640847, 6916.896246556281, 6924.549904256232, 6728.663566821664, 6812.284710105442, 6846.178458584976, 6849.931500685004, 6869.484788640847, 6483.254425741029, 6750.864982700343, 6823.249442561314, 6829.590224586515, 6863.7597684081065, 6673.8665226695675, 6696.7656132035145, 6710.362515561038, 6738.696758645849, 6772.322767723237, 6303.842403939892, 6587.670616469158, 6601.405873471411, 6605.207375115017, 6612.702428390734, 28509.572356414465, 28691.42617147666, 28799.856000720072, 28813.0, 29140.125796226148, 2992.880284788606, 2994.116735563014, 3008.819470831757, 3015.8492075396143, 3016.0951714485786, 16322.83677163237, 16518.0, 17103.40138095173, 18036.819631803646, 18063.367782127563, 1981.77209620894, 2029.4926268432891, 2060.6805945078536, 2063.7523497180437, 2070.7618623858298, 128486.3575682125, 128502.0, 130652.38695226137, 131154.0, 133327.0, 21629.0, 22279.443013924458, 22306.442338941655, 22392.55214895693, 22578.983945722153, 36896.52413903468, 37294.62705372966, 37681.0, 37712.0, 37727.30227139785, 9372.718818435458, 10754.0, 11112.277701949348, 11289.54841806335, 11344.659660210042, 7518.849623007619, 9046.86429703555, 9047.230985366186, 9057.682981095762, 9194.540272986324, 10704.78590428187, 10835.133189344833, 10852.782944341227, 10971.543963651584, 10976.451177441097, 3231.1275955491888, 3922.9408059823686, 4106.419028673922, 4135.1316223593385, 4253.553376895381, 1052.347544522401, 1079.109734469066, 1083.1551389915878, 1087.853139826129, 1127.5827943660734, 22471.88764056202, 22858.19996300155, 22888.0, 22919.54160916772, 22935.197268095537, 903.5346796399859, 1075.876274228458, 1091.1303272393714, 1117.2570240789785, 1121.8429419881222, 11319.26424782403, 11890.88109118915, 12042.458089385998, 12086.516539338503, 12108.334041627619, 724907.0, 738395.0, 738518.0, 745632.0, 758299.0, 131021.34489327684, 131397.0, 136811.0, 137743.0, 140222.0, 1268.3024336614824, 1271.141979164079, 1299.1944994103717, 1299.9675008124684, 1302.446460254388, 18843.24627014959, 19577.902110489358, 19912.004399780242, 20099.39701808948, 20108.69836952476, 62910.37089629045, 62971.0, 64571.70856582936, 64651.67674161599, 64830.67584662046, 80839.74541654774, 82847.0, 88167.55916220378, 90378.0, 92212.0, 8782.824343513223, 11546.711332216762, 11807.23252988553, 12110.394480276127, 12201.0, 8531.95734021326, 13176.341182941007, 13266.46934122663, 13276.668083297995, 13721.931390342985, 11539.538418463504, 11593.478293476644, 11671.649850504498, 11711.472983715923, 12090.032797376012, 10969.451527423756, 10994.450277486254, 11132.499037543168, 11215.439228038402, 11291.266067705574, 11800.350980696145, 11908.583199587973, 12219.57231496929, 12238.020958323135, 12298.323592202514, 11873.584424545099, 11919.46362413676, 11942.343171125323, 12270.0, 12295.47942671768, 11723.237989530653, 11740.471678774305, 11924.22492538017, 12021.75956480883, 12180.57367992116], "value": 11922.413411094652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1605.6226786705124, 1627.7151498487763, 1629.503001584517, 1650.7853978982723, 1662.2519866060272, 10791.514381852816, 11266.718332041703, 11280.774384512326, 11281.323120612758, 11304.378259195752, 10729.517171727271, 11146.331220126798, 11210.27133236341, 11219.38293393863, 11242.943785281062, 2372.79831214347, 2414.601590737535, 2420.9515809683794, 2423.297243799305, 2429.671994280776, 2269.4893648929024, 2321.535692861426, 2389.354874183969, 2390.306811024801, 2393.497365553236, 2319.2926157522033, 2377.2511658827502, 2383.0348708772976, 2397.2688330059327, 2405.6151015837468, 2292.667563203326, 2349.5066036132434, 2364.042562762084, 2367.8934447949796, 2371.3834403055157, 2090.770015298316, 2296.2881506732965, 2362.9527409451885, 2372.5373552157334, 2375.798057165143, 10738.194635402377, 11055.391953442557, 11055.889441105566, 11086.944565277123, 11131.33212007274, 5558.388577256498, 5718.571107166979, 5741.25363702716, 5749.568782341299, 5755.366909639936, 3339.2987472630775, 3481.3037392521596, 3557.288542291552, 3564.1267889367273, 3573.678368946781, 1165.8600967883908, 1167.4221260476033, 1176.1237877781023, 1206.7586482703493, 1216.0271782573993, 13651.453941842314, 13974.951878609145, 14237.64405889861, 15251.542453726295, 15467.613309667237, 4560.817567297271, 4684.906301873943, 4710.175719249118, 4719.693219940693, 4720.88197795051, 10161.18710503158, 11505.597304094485, 11535.134864885167, 11551.942240288745, 11554.017908477874, 4912.754362281871, 5551.639143455663, 5735.369109397963, 5741.540676745851, 5764.279465066867, 4256.34026725858, 5257.553107985864, 5260.263563101169, 5268.92096618551, 5340.118880384693, 3823.9426408603895, 3842.442845787345, 3872.1287710264965, 3894.2600905827803, 3905.7461265017682, 867.8568036274071, 1005.2309982863115, 1007.496251874063, 1011.5346940407347, 1025.1593693171676, 806.435495153395, 847.8643417053241, 850.2815121222557, 872.7207293665966, 900.6442455230148, 6699.39705426514, 6714.966425167843, 6904.516683832184, 6917.169939607279, 6962.582245065312, 1002.4837208837455, 1011.7217765114506, 1019.8317277649105, 1049.071571659087, 1072.383379556758, 4203.831846726097, 4270.0605866709275, 4319.8451881807005, 4377.737335759864, 4513.323001549728, 11832.875876791692, 13187.67030824237, 13430.798538021938, 13792.793108103386, 13843.0, 13000.154989925624, 13737.793933091012, 13753.243571603458, 13772.96702747278, 13777.242251676254, 9945.0, 10203.336783109075, 10204.897951020543, 10216.693499195037, 10236.641717539851, 6050.45545900871, 6857.611128877622, 6915.515913886181, 7013.614251216232, 7024.297570242935, 10398.324108932895, 12018.75962480763, 12090.879091208975, 12271.877281227073, 12328.876711233132, 12331.321777301975, 12429.316387598768, 12462.189957652723, 12477.376131193585, 12587.559435419716, 9298.907010929805, 9300.39547429415, 9468.715938521855, 9509.857352139585, 9527.666531671359, 10385.792284154426, 10397.11624511925, 10485.633002844865, 10504.78990420172, 10507.684769456622, 8739.868901966604, 8893.73318800437, 8944.37389382737, 8960.551972401485, 8974.551272436483, 8561.957190214258, 8669.956650216709, 8745.562721863755, 8785.780355491164, 8818.867716984121, 7745.109312429143, 7970.202979701983, 8028.799280017813, 8031.678732850854, 8038.276555110068, 5843.2695913010875, 5867.882642347215, 5929.318128415288, 6047.395260473917, 6136.2636483622255, 5911.556633252438, 5952.226210592596, 5975.073863551156, 6195.287541932736, 6228.408301211378], "value": 5116.225987269162, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.39421680251974, 383.8502983836303, 385.1507426125394, 390.23319177815597, 393.6948864629911, 4405.770460358052, 4989.625778066646, 5033.295338652587, 5039.44566097729, 5081.898362032757, 4273.615374616284, 4390.429244198258, 4628.7685615719165, 4938.7530623468865, 5005.274235235888, 4056.472658554384, 4146.792660366969, 4185.6651467882575, 4204.894877628069, 4223.408722778813, 3610.9277814443694, 3708.8516459341595, 3919.8824035278985, 4032.717709760318, 4173.499180098393, 3569.7858128512244, 3628.675533430305, 3869.8645547405845, 4206.852760153395, 4242.554531774165, 3774.414965680338, 4083.121035016716, 4113.4035564843225, 4237.00430398856, 4274.636655884254, 3844.9807750961345, 3871.4386413969814, 3879.4180872869015, 3881.844726210948, 3902.2585708715255, 4045.9190816183805, 4462.040661257813, 4759.904801903943, 4875.7074575525585, 4985.2581080406, 4724.952750472486, 4890.2370160859, 4899.779509922062, 4907.503211520485, 4961.404631444214, 5120.670095106718, 5325.627206095575, 5329.175536965415, 5363.597730170253, 5386.622936394454, 3770.7171962102952, 4318.632916202127, 4335.848245311399, 4351.151525452507, 4372.912541749179, 4164.0977462004375, 4797.2564252540915, 5122.595661577035, 5276.973615131899, 5459.617826752129, 3914.8042597869994, 3933.370660694275, 4171.124063946544, 4371.693981421334, 4410.977945110318, 3931.859760669374, 4485.506594274627, 4520.660950428731, 4527.637788976874, 4549.998000879602, 4374.059577190919, 4551.4765801932535, 4821.31169655214, 4860.756962151878, 4928.408590969107, 4442.4669039715445, 5176.870578235499, 5183.688978661293, 5273.515543834369, 5456.590755693339, 1466.3796504861973, 1497.341169885247, 1520.6502504424045, 1521.6761417566568, 1527.4496386168048, 1033.89661033896, 1091.9781604367868, 1127.577158565538, 1156.3582211872324, 1177.717347836513, 4533.478649955231, 4611.164134057531, 4826.638002149853, 4869.537393947569, 4905.3623029006, 4780.808767649325, 5062.392512898402, 5204.9219261711105, 5570.844217140083, 5572.60991730575, 3970.344893092665, 4143.744454303449, 4616.884577885513, 4830.782614782343, 4844.660873738804, 2446.412860913368, 2855.9286017849304, 2857.5427931530853, 2862.2415060009116, 2888.0, 4691.7184968901975, 4806.855794326176, 4959.504049595083, 5230.869228269247, 5347.171188465794, 4728.692634978715, 4801.327814106028, 5196.558292545176, 5666.575006874501, 5728.713564321767, 5030.641726733761, 5485.122380419273, 5570.8775506125985, 5620.634658747168, 5626.971865140648, 5206.817761378334, 5218.356217791384, 5539.14143307788, 5566.248556444828, 5616.073347897551, 5159.845204643867, 5199.92200116991, 5208.452997557589, 5523.502884740232, 5526.944730552804, 4634.4670362907345, 5430.131179011497, 5716.314042314949, 5755.424457554211, 5779.872924779627, 4393.132918509685, 4452.510223875306, 4455.858619673058, 4481.602342746615, 4483.574060464251, 4730.881727956828, 5191.636585438983, 5214.0, 5546.722663866871, 5613.831585052455, 5174.136311866833, 5367.785288588569, 5367.838964831061, 5379.579189364723, 5456.918146227889, 4962.42932062803, 5380.43505431924, 5389.8922021558565, 5407.080796264566, 5412.648177868426, 4419.889502762328, 4927.926081108858, 5268.9736551317, 5290.635225938693, 5334.293026231724, 5340.385855626652, 5388.655934668432, 5408.0806262936185, 5422.213779002102, 5436.320459942507, 4612.469565999953, 4653.9761252524395, 5257.710825904612, 5271.841844744664, 5272.420033796203], "value": 4088.7728886715595, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.5670467833337, 766.8236305649697, 788.6805843633327, 860.9182127697868, 878.5519385113593, 14031.649208769773, 15104.244787760623, 15394.384224631001, 15933.920330398332, 15936.362545498167, 14744.778828317583, 14891.329890154942, 14893.180875051868, 14896.25518724065, 14899.627509312273, 974.2206235011997, 988.9950550247239, 1007.4660429972126, 1011.7824667696444, 1022.7187523431041, 1023.8668973033496, 1024.068098030792, 1024.1653052762013, 1024.810410074135, 1026.743314171457, 1069.4599227390197, 1069.7806949575318, 1070.202698989254, 1071.8338657508098, 1072.9141668666489, 1062.2988827373936, 1062.6865074802952, 1063.0432610650398, 1063.415121683079, 1068.1668298726966, 793.6309616028517, 794.5788731972063, 796.9920300796975, 799.7280924485644, 801.3348920396115, 15878.444254451038, 16299.592510187222, 16406.507804765875, 16502.42241521553, 16526.091064991422, 14317.85682143186, 14359.425622975068, 14553.563393098224, 14568.927155364261, 14597.708045839025, 11718.12114091438, 11798.41007949599, 11804.763904721944, 11906.940465297617, 12311.138220324581, 3640.199156185635, 3706.9073273168115, 3914.5889681583594, 3923.058465968176, 3927.7839718815453, 91271.0, 108185.37721934177, 119544.40227798805, 121680.0, 123557.38221308925, 10658.307210031324, 10673.092787113183, 10950.233483656068, 11329.886701133048, 11501.367424791717, 23373.71444570565, 23740.88129559341, 23761.0, 23762.881185594306, 23816.285511434686, 7867.567283799446, 7906.90467026227, 7925.365970722329, 7997.240262175084, 8061.838763224702, 4351.129774045204, 4406.559344065632, 4537.1833070047705, 4662.0209755950955, 4675.228587283128, 2823.957640635392, 3505.4391297392804, 3507.4212754895666, 3509.701675357572, 3527.4532447470683, 574.913762935563, 593.5133190783516, 601.8044135655929, 624.893768059431, 625.0967352176062, 682.5563383800569, 695.151914664109, 714.5605452646585, 716.451914285572, 741.6217728958162, 9244.907550924405, 10426.113780328606, 10465.9476702616, 10547.0, 10673.199510036779, 591.340655169483, 601.5680542504998, 609.4423602403751, 620.6803496199461, 642.1267076775581, 4297.355396690521, 4770.833020844323, 5057.165567681365, 5057.747112644353, 5073.0, 77295.61352193315, 94040.52979735195, 98237.50881245547, 98700.0, 99383.0, 80054.0, 89190.0, 90552.0, 92099.53950230205, 92279.53860230657, 51654.48345516497, 65909.0, 67651.0, 72163.27836721565, 76792.0, 9792.755181120529, 10265.281430299807, 11179.217454778413, 11331.376774277494, 11342.489587968395, 47311.29033064438, 47645.52354476411, 48881.0, 49442.25836612382, 50047.74976125096, 56729.86540269254, 61198.38801612105, 61641.07538387017, 62417.43956401126, 66829.66585167237, 13027.609171724864, 13137.73724525485, 13317.7744121957, 13839.930800345934, 13905.550223928245, 14651.926740366656, 15000.474983375529, 15012.849871501145, 15115.773263401277, 15564.766528502309, 13414.262215578237, 13903.652408689459, 14281.571552853431, 14788.038777479429, 14848.109113453227, 13162.341882905625, 13283.535076272285, 14122.858771412566, 14127.293635318398, 14671.55985320442, 13052.738945221232, 13761.38073786662, 13952.511662092184, 14028.78956815669, 14236.501722439665, 9271.953640231755, 9784.755381115529, 9910.851337230091, 10150.23873209527, 10158.984101589782, 9296.117107058677, 11367.374794386387, 11477.655670329903, 11543.076553875502, 11544.365059921785], "value": 7730.75232596961, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1462.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 117.0, 116.0, 117.0, 115.0, 117.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 106.0, 142.0, 107.0, 105.0, 110.0, 107.0, 127.0, 107.0], "value": 116.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 186.0, 186.0, 185.0, 185.0, 252.0, 239.0, 187.0], "value": 197.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 58.0, 88.0, 60.0, 63.0, 63.0, 78.0, 98.0, 82.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 76.0, 89.0, 69.0, 69.0, 69.0, 81.0, 69.0, 69.0, 84.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 102.0, 135.0, 86.0, 86.0, 87.0, 87.0, 86.0, 86.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 95.0, 108.0, 105.0, 94.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 86.0, 85.0, 83.0, 86.0, 83.0, 86.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 64.0, 63.0, 62.0, 62.0, 62.0, 63.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 64.0, 63.0, 65.0, 79.0, 83.0, 83.0, 84.0, 60.0, 57.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 80.0, 77.0, 84.0, 70.0, 104.0, 100.0, 100.0, 78.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 136.0, 135.0, 132.0, 153.0, 149.0, 147.0, 178.0, 157.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 173.0, 168.0, 164.0, 165.0, 215.0, 222.0, 171.0, 161.0], "value": 176.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 79.0, 84.0, 81.0, 82.0, 70.0, 66.0, 64.0, 63.0, 64.0], "value": 71.8, "unit": "score"}]}]} 17:44:10 INFO - Return code: 0 17:44:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:44:10 INFO - # TBPL SUCCESS # 17:44:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:44:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:44:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:44:10 INFO - 2017-05-24 17:44:10.635 screenresolution[2266:18825] starting screenresolution argv=screenresolution get 17:44:10 INFO - 2017-05-24 17:44:10.638 screenresolution[2266:18825] Display 0: 1600x1200x32@60 17:44:10 INFO - 2017-05-24 17:44:10.644 screenresolution[2267:18828] starting screenresolution argv=screenresolution list 17:44:10 INFO - Available Modes on Display 0 17:44:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:44:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:44:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:44:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:44:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:44:10 INFO - Intel Iris: 17:44:10 INFO - Chipset Model: Intel Iris 17:44:10 INFO - Type: GPU 17:44:10 INFO - Bus: Built-In 17:44:10 INFO - VRAM (Dynamic, Max): 1536 MB 17:44:10 INFO - Vendor: Intel (0x8086) 17:44:10 INFO - Device ID: 0x0a2e 17:44:10 INFO - Revision ID: 0x0009 17:44:10 INFO - Displays: 17:44:10 INFO - Display: 17:44:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:44:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:44:10 INFO - Display Serial Number: 1600x1200 60 17:44:10 INFO - Main Display: Yes 17:44:10 INFO - Mirror: Off 17:44:10 INFO - Online: Yes 17:44:10 INFO - Rotation: Supported 17:44:10 INFO - Return code: 0 17:44:10 INFO - Running post-action listener: _resource_record_post_action 17:44:10 INFO - [mozharness: 2017-05-25 00:44:10.878581Z] Finished run-tests step (success) 17:44:10 INFO - Running post-run listener: _resource_record_post_run 17:44:10 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 1011712; Write bytes: 510697472; Read time: 13; Write time: 1630 17:44:10 INFO - TinderboxPrint: CPU usage
53.9% 17:44:10 INFO - TinderboxPrint: I/O read bytes / time
1,011,712 / 13 17:44:10 INFO - TinderboxPrint: I/O write bytes / time
510,697,472 / 1,630 17:44:10 INFO - TinderboxPrint: CPU idle
2,212.3 (46.1%) 17:44:10 INFO - TinderboxPrint: CPU system
961.5 (20.0%) 17:44:10 INFO - TinderboxPrint: CPU user
1,623.8 (33.8%) 17:44:10 INFO - TinderboxPrint: Swap in / out
209,944,576 / 0 17:44:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208146944; Write bytes: 202618880; Read time: 15808; Write time: 429 17:44:10 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 925696; Write bytes: 297932800; Read time: 11; Write time: 1163 17:44:11 INFO - Running post-run listener: _upload_blobber_files 17:44:11 INFO - Blob upload gear active. 17:44:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:44:11 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 17:44:11 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'] 17:44:11 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 17:44:11 INFO - (blobuploader) - INFO - Open directory for files ... 17:44:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:44:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:44:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:12 INFO - (blobuploader) - INFO - Done attempting. 17:44:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:44:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:44:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:13 INFO - (blobuploader) - INFO - Done attempting. 17:44:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:44:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:44:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:14 INFO - (blobuploader) - INFO - Done attempting. 17:44:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:44:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:44:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:16 INFO - (blobuploader) - INFO - Done attempting. 17:44:16 INFO - (blobuploader) - INFO - Iteration through files over. 17:44:16 INFO - Return code: 0 17:44:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:44:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:44:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0dfa63cea168a4334c02d32d4d679c4a287f0778f2d407b06d9905955e188896f334af422b8742511f7c659f4e6093b512a01580f90651d0dc909317fb1765e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62087359b72cb8698b36609dcf1b96fe18a204c883a2aa7cffc9b65f9016b4aa8db4878cdc318a2ec6f0ac3298c88952adf7e9f8ceef88508741b7b181e7ddbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a69aea7c12325e6d81e79eee49f5049d08b5601fcd07d3b36297bef8a1e6f2b29f89491cff17aa7e4753d8aedf694fe1f5f7f002c1801bfc5f8b7ff4e9420e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca94f613135bee6e609a85eecbd957997401c12d9a40cb29f94f0b2a1ca37e2bddab14bb2c5b226fbb14dd829d3305d3e8101acf428f90c0447c4e8b41fd94b"} 17:44:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:44:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:44:16 INFO - Contents: 17:44:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0dfa63cea168a4334c02d32d4d679c4a287f0778f2d407b06d9905955e188896f334af422b8742511f7c659f4e6093b512a01580f90651d0dc909317fb1765e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62087359b72cb8698b36609dcf1b96fe18a204c883a2aa7cffc9b65f9016b4aa8db4878cdc318a2ec6f0ac3298c88952adf7e9f8ceef88508741b7b181e7ddbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a69aea7c12325e6d81e79eee49f5049d08b5601fcd07d3b36297bef8a1e6f2b29f89491cff17aa7e4753d8aedf694fe1f5f7f002c1801bfc5f8b7ff4e9420e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca94f613135bee6e609a85eecbd957997401c12d9a40cb29f94f0b2a1ca37e2bddab14bb2c5b226fbb14dd829d3305d3e8101acf428f90c0447c4e8b41fd94b"} 17:44:16 INFO - Running post-run listener: copy_logs_to_upload_dir 17:44:16 INFO - Copying logs to upload dir... 17:44:16 INFO - mkdir: /builds/slave/test/build/upload/logs 17:44:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.543061 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-24 17:44:16.558058) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 17:44:16.561685) ========= 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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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/c0dfa63cea168a4334c02d32d4d679c4a287f0778f2d407b06d9905955e188896f334af422b8742511f7c659f4e6093b512a01580f90651d0dc909317fb1765e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62087359b72cb8698b36609dcf1b96fe18a204c883a2aa7cffc9b65f9016b4aa8db4878cdc318a2ec6f0ac3298c88952adf7e9f8ceef88508741b7b181e7ddbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a69aea7c12325e6d81e79eee49f5049d08b5601fcd07d3b36297bef8a1e6f2b29f89491cff17aa7e4753d8aedf694fe1f5f7f002c1801bfc5f8b7ff4e9420e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca94f613135bee6e609a85eecbd957997401c12d9a40cb29f94f0b2a1ca37e2bddab14bb2c5b226fbb14dd829d3305d3e8101acf428f90c0447c4e8b41fd94b"} build_url:https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010992 build_url: 'https://queue.taskcluster.net/v1/task/anONCSbIRC2kopwIhC5XXw/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/c0dfa63cea168a4334c02d32d4d679c4a287f0778f2d407b06d9905955e188896f334af422b8742511f7c659f4e6093b512a01580f90651d0dc909317fb1765e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62087359b72cb8698b36609dcf1b96fe18a204c883a2aa7cffc9b65f9016b4aa8db4878cdc318a2ec6f0ac3298c88952adf7e9f8ceef88508741b7b181e7ddbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a69aea7c12325e6d81e79eee49f5049d08b5601fcd07d3b36297bef8a1e6f2b29f89491cff17aa7e4753d8aedf694fe1f5f7f002c1801bfc5f8b7ff4e9420e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9ca94f613135bee6e609a85eecbd957997401c12d9a40cb29f94f0b2a1ca37e2bddab14bb2c5b226fbb14dd829d3305d3e8101acf428f90c0447c4e8b41fd94b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 17:44:16.594455) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 17:44:16.594803) ========= 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.pDlvuj5s2q/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.mdLtLZqKSN/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.005138 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 17:44:16.643041) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 17:44:16.643370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 17:44:16.643679) ========= ========= Total master_lag: 0.18 =========