builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0219 starttime: 1495799733.51 results: success (0) buildid: 20170526031615 builduid: c08e071ca146477f8373f9e592013c48 revision: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.507491) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.507931) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.508204) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.532006) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.532396) ========= 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.Q0BgViBQyY/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.EGjnbAURCu/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-26 04:55:33-- 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-26 04:55:33 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134643 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.689446) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.689768) ========= 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.Q0BgViBQyY/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.EGjnbAURCu/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.022210 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.741547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:33.741905) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad --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 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Q0BgViBQyY/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.EGjnbAURCu/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-26 04:55:33,826 truncating revision to first 12 chars 2017-05-26 04:55:33,826 Setting DEBUG logging. 2017-05-26 04:55:33,826 attempt 1/10 2017-05-26 04:55:33,826 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9675c8ebd64e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 04:55:34,458 unpacking tar archive at: mozilla-beta-9675c8ebd64e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.896853 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:34.659938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:34.660256) ========= script_repo_revision: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:34.660655) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:34.660918) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 04:55:34.679773) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-26 04:55:34.680141) ========= /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.Q0BgViBQyY/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.EGjnbAURCu/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:55:34 INFO - MultiFileLogger online at 20170526 04:55:34 in /builds/slave/test 04:55:34 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 04:55:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:55:34 INFO - {'append_to_log': False, 04:55:34 INFO - 'base_work_dir': '/builds/slave/test', 04:55:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:55:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:55:34 INFO - 'branch': 'Mozilla-Beta', 04:55:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:55:34 INFO - 'config_files': ('talos/mac_config.py',), 04:55:34 INFO - 'default_actions': ('clobber', 04:55:34 INFO - 'read-buildbot-config', 04:55:34 INFO - 'download-and-extract', 04:55:34 INFO - 'populate-webroot', 04:55:34 INFO - 'create-virtualenv', 04:55:34 INFO - 'install', 04:55:34 INFO - 'run-tests'), 04:55:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:55:34 INFO - 'download_minidump_stackwalk': True, 04:55:34 INFO - 'download_symbols': 'ondemand', 04:55:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:55:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:55:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:55:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:55:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:55:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:55:34 INFO - 'gecko_profile': False, 04:55:34 INFO - 'gecko_profile_interval': 0, 04:55:34 INFO - 'installer_path': 'installer.exe', 04:55:34 INFO - 'log_level': 'info', 04:55:34 INFO - 'log_name': 'talos', 04:55:34 INFO - 'log_to_console': True, 04:55:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:55:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:55:34 INFO - 'opt_config_files': (), 04:55:34 INFO - 'pip_index': False, 04:55:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:34 INFO - 'cmd': ('bash', 04:55:34 INFO - '-c', 04:55:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:34 INFO - 'enabled': True, 04:55:34 INFO - 'halt_on_failure': False, 04:55:34 INFO - 'name': 'check_screen_resolution'},), 04:55:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:34 INFO - 'cmd': ('bash', 04:55:34 INFO - '-c', 04:55:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:34 INFO - 'enabled': True, 04:55:34 INFO - 'halt_on_failure': False, 04:55:34 INFO - 'name': 'check_screen_resolution'},), 04:55:34 INFO - 'run_cmd_checks_enabled': True, 04:55:34 INFO - 'suite': 'dromaeojs', 04:55:34 INFO - 'system_bits': '32', 04:55:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:55:34 INFO - 'title': 't-yosemite-r7-0219', 04:55:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:55:34 INFO - 'use_talos_json': True, 04:55:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:55:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:55:34 INFO - 'work_dir': 'build'} 04:55:34 INFO - [mozharness: 2017-05-26 11:55:34.869405Z] Running clobber step. 04:55:34 INFO - Running pre-action listener: _resource_record_pre_action 04:55:34 INFO - Running main action method: clobber 04:55:34 INFO - rmtree: /builds/slave/test/build 04:55:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:55:38 INFO - Running post-action listener: _resource_record_post_action 04:55:38 INFO - [mozharness: 2017-05-26 11:55:38.133463Z] Finished clobber step (success) 04:55:38 INFO - [mozharness: 2017-05-26 11:55:38.133617Z] Running read-buildbot-config step. 04:55:38 INFO - Running pre-action listener: _resource_record_pre_action 04:55:38 INFO - Running main action method: read_buildbot_config 04:55:38 INFO - Using buildbot properties: 04:55:38 INFO - { 04:55:38 INFO - "project": "", 04:55:38 INFO - "product": "firefox", 04:55:38 INFO - "script_repo_revision": "production", 04:55:38 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 04:55:38 INFO - "repository": "", 04:55:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 04:55:38 INFO - "buildid": "20170526031615", 04:55:38 INFO - "pgo_build": "False", 04:55:38 INFO - "basedir": "/builds/slave/test", 04:55:38 INFO - "buildnumber": 1, 04:55:38 INFO - "slavename": "t-yosemite-r7-0219", 04:55:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:55:38 INFO - "platform": "yosemite_r7_devedition", 04:55:38 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 04:55:38 INFO - "branch": "mozilla-beta", 04:55:38 INFO - "repo_path": "releases/mozilla-beta", 04:55:38 INFO - "revision": "9675c8ebd64e924a10fb435d59d7bd7d1069b1ad", 04:55:38 INFO - "stage_platform": "macosx64-devedition", 04:55:38 INFO - "builduid": "c08e071ca146477f8373f9e592013c48", 04:55:38 INFO - "slavebuilddir": "test" 04:55:38 INFO - } 04:55:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:55:38 INFO - Running post-action listener: _resource_record_post_action 04:55:38 INFO - [mozharness: 2017-05-26 11:55:38.136789Z] Finished read-buildbot-config step (success) 04:55:38 INFO - [mozharness: 2017-05-26 11:55:38.136916Z] Running download-and-extract step. 04:55:38 INFO - Running pre-action listener: _resource_record_pre_action 04:55:38 INFO - Running main action method: download_and_extract 04:55:38 INFO - mkdir: /builds/slave/test/build/tests 04:55:38 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')]} 04:55:38 INFO - trying https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:55:38 INFO - Downloading https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/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 04:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/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 04:55:39 INFO - Downloaded 1287 bytes. 04:55:39 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:55:39 INFO - Using the following test package requirements: 04:55:39 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:55:39 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:55:39 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:55:39 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:55:39 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:55:39 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:55:39 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:55:39 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:55:39 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:55:39 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:55:39 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:55:39 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:55:39 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:55:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:55:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:55:39 INFO - Fetch https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:55:41 INFO - Expected file size: 51967544 04:55:41 INFO - Obtained file size: 51967544 04:55:56 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 04:55:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:55:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:55:56 INFO - Fetch https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:55:58 INFO - Expected file size: 51967544 04:55:58 INFO - Obtained file size: 51967544 04:56:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:56:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:56:14 INFO - Fetch https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:56:15 INFO - Expected file size: 14404449 04:56:15 INFO - Obtained file size: 14404449 04:56:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:15 INFO - trying https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:56:15 INFO - Downloading https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:56:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/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 04:56:17 INFO - Downloaded 74890852 bytes. 04:56:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:56:17 INFO - mkdir: /builds/slave/test/properties 04:56:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:56:17 INFO - Writing to file /builds/slave/test/properties/build_url 04:56:17 INFO - Contents: 04:56:17 INFO - build_url:https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:56:18 INFO - Running post-action listener: _resource_record_post_action 04:56:18 INFO - Running post-action listener: set_extra_try_arguments 04:56:18 INFO - [mozharness: 2017-05-26 11:56:18.496807Z] Finished download-and-extract step (success) 04:56:18 INFO - [mozharness: 2017-05-26 11:56:18.497015Z] Running populate-webroot step. 04:56:18 INFO - Running pre-action listener: _resource_record_pre_action 04:56:18 INFO - Running main action method: populate_webroot 04:56:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:56:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:56:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:56:18 INFO - '--tppagecycles', 04:56:18 INFO - '7'], 04:56:18 INFO - 'tests': ['tsvgm']}}, 04:56:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:56:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:56:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:56:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:56:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['damp', 'tps']}, 04:56:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'tests': ['damp', 'tps']}, 04:56:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['dromaeo_dom']}, 04:56:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:56:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['basic_compositor_video']}, 04:56:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:56:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['a11yr', 04:56:18 INFO - 'ts_paint', 04:56:18 INFO - 'tpaint', 04:56:18 INFO - 'sessionrestore', 04:56:18 INFO - 'sessionrestore_no_auto_restore', 04:56:18 INFO - 'tabpaint']}, 04:56:18 INFO - 'other-e10s': {'tests': ['a11yr', 04:56:18 INFO - 'ts_paint', 04:56:18 INFO - 'tpaint', 04:56:18 INFO - 'sessionrestore', 04:56:18 INFO - 'sessionrestore_no_auto_restore', 04:56:18 INFO - 'tabpaint']}, 04:56:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:56:18 INFO - 'ts_paint', 04:56:18 INFO - 'tpaint', 04:56:18 INFO - 'sessionrestore', 04:56:18 INFO - 'sessionrestore_no_auto_restore', 04:56:18 INFO - 'tabpaint']}, 04:56:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:56:18 INFO - 'ts_paint', 04:56:18 INFO - 'tpaint', 04:56:18 INFO - 'sessionrestore', 04:56:18 INFO - 'sessionrestore_no_auto_restore', 04:56:18 INFO - 'tabpaint']}, 04:56:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['a11yr', 04:56:18 INFO - 'ts_paint', 04:56:18 INFO - 'tpaint', 04:56:18 INFO - 'sessionrestore', 04:56:18 INFO - 'sessionrestore_no_auto_restore', 04:56:18 INFO - 'tabpaint']}, 04:56:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['a11yr', 04:56:18 INFO - 'ts_paint', 04:56:18 INFO - 'tpaint', 04:56:18 INFO - 'sessionrestore', 04:56:18 INFO - 'sessionrestore_no_auto_restore', 04:56:18 INFO - 'tabpaint']}, 04:56:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['tsvgx', 04:56:18 INFO - 'tsvgr_opacity', 04:56:18 INFO - 'tart', 04:56:18 INFO - 'tscrollx', 04:56:18 INFO - 'cart', 04:56:18 INFO - 'tsvg_static']}, 04:56:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:56:18 INFO - 'tsvgr_opacity', 04:56:18 INFO - 'tart', 04:56:18 INFO - 'tscrollx', 04:56:18 INFO - 'cart', 04:56:18 INFO - 'tsvg_static']}, 04:56:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'talos_options': ['--disable-e10s'], 04:56:18 INFO - 'tests': ['tp5o']}, 04:56:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:56:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'talos_options': ['--disable-e10s', 04:56:18 INFO - '--xperf_path', 04:56:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:18 INFO - 'tests': ['tp5n']}, 04:56:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:56:18 INFO - 'talos_options': ['--xperf_path', 04:56:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:18 INFO - 'tests': ['tp5n']}}, 04:56:18 INFO - 'talos.zip': {'path': '', 04:56:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:56:18 INFO - Running post-action listener: _resource_record_post_action 04:56:18 INFO - [mozharness: 2017-05-26 11:56:18.515745Z] Finished populate-webroot step (success) 04:56:18 INFO - [mozharness: 2017-05-26 11:56:18.515860Z] Running create-virtualenv step. 04:56:18 INFO - Running pre-action listener: _resource_record_pre_action 04:56:18 INFO - Running main action method: create_virtualenv 04:56:18 INFO - Creating virtualenv /builds/slave/test/build/venv 04:56:18 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 04:56:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:56:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:56:18 INFO - Using real prefix '/tools/python27' 04:56:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:56:19 INFO - Installing distribute.............................................................................................................................................................................................done. 04:56:22 INFO - Installing pip.................done. 04:56:22 INFO - Return code: 0 04:56:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:56:22 INFO - Reading from file tmpfile_stdout 04:56:22 INFO - Output received: 04:56:22 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) 04:56:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:56:22 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')]} 04:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:22 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')]} 04:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:22 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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:22 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 04:56:22 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 04:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:22 INFO - 'HOME': '/Users/cltbld', 04:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:22 INFO - 'LOGNAME': 'cltbld', 04:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:22 INFO - 'MOZ_NO_REMOTE': '1', 04:56:22 INFO - 'NO_EM_RESTART': '1', 04:56:22 INFO - 'PAGER': '/bin/cat', 04:56:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:22 INFO - 'PWD': '/builds/slave/test', 04:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:22 INFO - 'SHELL': '/bin/bash', 04:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:22 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:22 INFO - 'USER': 'cltbld', 04:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:22 INFO - 'XPC_FLAGS': '0x0', 04:56:22 INFO - 'XPC_SERVICE_NAME': '0', 04:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:22 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 04:56:22 INFO - Cleaning up... 04:56:22 INFO - Return code: 0 04:56:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:56:22 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')]} 04:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:22 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')]} 04:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:22 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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:22 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 04:56:22 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 04:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:22 INFO - 'HOME': '/Users/cltbld', 04:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:22 INFO - 'LOGNAME': 'cltbld', 04:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:22 INFO - 'MOZ_NO_REMOTE': '1', 04:56:22 INFO - 'NO_EM_RESTART': '1', 04:56:22 INFO - 'PAGER': '/bin/cat', 04:56:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:22 INFO - 'PWD': '/builds/slave/test', 04:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:22 INFO - 'SHELL': '/bin/bash', 04:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:22 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:22 INFO - 'USER': 'cltbld', 04:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:22 INFO - 'XPC_FLAGS': '0x0', 04:56:22 INFO - 'XPC_SERVICE_NAME': '0', 04:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:22 INFO - Downloading/unpacking psutil>=3.1.1 04:56:22 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 04:56:22 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 04:56:22 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 04:56:22 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 04:56:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:56:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:24 INFO - Installing collected packages: psutil 04:56:24 INFO - Running setup.py install for psutil 04:56:24 INFO - building 'psutil._psutil_osx' extension 04:56:24 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 04:56:25 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 04:56:25 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 04:56:25 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 04:56:25 INFO - building 'psutil._psutil_posix' extension 04:56:25 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 04:56:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:56:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:56:25 INFO - ^ 04:56:25 INFO - 1 warning generated. 04:56:25 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 04:56:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:25 INFO - Successfully installed psutil 04:56:25 INFO - Cleaning up... 04:56:25 INFO - Return code: 0 04:56:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:56:25 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')]} 04:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:25 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')]} 04:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:25 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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:25 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 04:56:25 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 04:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:25 INFO - 'HOME': '/Users/cltbld', 04:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:25 INFO - 'LOGNAME': 'cltbld', 04:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:25 INFO - 'MOZ_NO_REMOTE': '1', 04:56:25 INFO - 'NO_EM_RESTART': '1', 04:56:25 INFO - 'PAGER': '/bin/cat', 04:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:25 INFO - 'PWD': '/builds/slave/test', 04:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:25 INFO - 'SHELL': '/bin/bash', 04:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:25 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:25 INFO - 'USER': 'cltbld', 04:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:25 INFO - 'XPC_FLAGS': '0x0', 04:56:25 INFO - 'XPC_SERVICE_NAME': '0', 04:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:56:27 INFO - Installing collected packages: mozsystemmonitor 04:56:27 INFO - Running setup.py install for mozsystemmonitor 04:56:27 INFO - Successfully installed mozsystemmonitor 04:56:27 INFO - Cleaning up... 04:56:27 INFO - Return code: 0 04:56:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:56:27 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')]} 04:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:27 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')]} 04:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:27 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 04:56:27 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 04:56:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:27 INFO - 'HOME': '/Users/cltbld', 04:56:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:27 INFO - 'LOGNAME': 'cltbld', 04:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:27 INFO - 'MOZ_NO_REMOTE': '1', 04:56:27 INFO - 'NO_EM_RESTART': '1', 04:56:27 INFO - 'PAGER': '/bin/cat', 04:56:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:27 INFO - 'PWD': '/builds/slave/test', 04:56:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:27 INFO - 'SHELL': '/bin/bash', 04:56:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:27 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:27 INFO - 'USER': 'cltbld', 04:56:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:27 INFO - 'XPC_FLAGS': '0x0', 04:56:27 INFO - 'XPC_SERVICE_NAME': '0', 04:56:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:27 INFO - Downloading/unpacking jsonschema==2.5.1 04:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:56:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:56:29 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 04:56:29 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 04:56:29 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 04:56:29 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 04:56:29 INFO - Downloading functools32-3.2.3-2.tar.gz 04:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:56:29 INFO - warning: no files found matching '*.txt' 04:56:29 INFO - no previously-included directories found matching 'build' 04:56:29 INFO - no previously-included directories found matching 'dist' 04:56:29 INFO - no previously-included directories found matching '.git*' 04:56:29 INFO - Installing collected packages: jsonschema, functools32 04:56:29 INFO - Running setup.py install for functools32 04:56:29 INFO - warning: no files found matching '*.txt' 04:56:29 INFO - no previously-included directories found matching 'build' 04:56:29 INFO - no previously-included directories found matching 'dist' 04:56:29 INFO - no previously-included directories found matching '.git*' 04:56:29 INFO - Successfully installed jsonschema functools32 04:56:29 INFO - Cleaning up... 04:56:29 INFO - Return code: 0 04:56:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:56:29 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')]} 04:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:30 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')]} 04:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:31 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 04:56:31 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 04:56:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:31 INFO - 'HOME': '/Users/cltbld', 04:56:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:31 INFO - 'LOGNAME': 'cltbld', 04:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:31 INFO - 'MOZ_NO_REMOTE': '1', 04:56:31 INFO - 'NO_EM_RESTART': '1', 04:56:31 INFO - 'PAGER': '/bin/cat', 04:56:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:31 INFO - 'PWD': '/builds/slave/test', 04:56:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:31 INFO - 'SHELL': '/bin/bash', 04:56:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:31 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:31 INFO - 'USER': 'cltbld', 04:56:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:31 INFO - 'XPC_FLAGS': '0x0', 04:56:31 INFO - 'XPC_SERVICE_NAME': '0', 04:56:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:56:31 INFO - Cleaning up... 04:56:31 INFO - Return code: 0 04:56:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:56: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')]} 04:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56: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')]} 04:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:31 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 04:56:31 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 04:56:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:31 INFO - 'HOME': '/Users/cltbld', 04:56:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:31 INFO - 'LOGNAME': 'cltbld', 04:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:31 INFO - 'MOZ_NO_REMOTE': '1', 04:56:31 INFO - 'NO_EM_RESTART': '1', 04:56:31 INFO - 'PAGER': '/bin/cat', 04:56:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:31 INFO - 'PWD': '/builds/slave/test', 04:56:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:31 INFO - 'SHELL': '/bin/bash', 04:56:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:31 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:31 INFO - 'USER': 'cltbld', 04:56:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:31 INFO - 'XPC_FLAGS': '0x0', 04:56:31 INFO - 'XPC_SERVICE_NAME': '0', 04:56:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:32 INFO - Downloading/unpacking blobuploader==1.2.4 04:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:33 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56:34 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 04:56:34 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 04:56:34 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 04:56:34 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 04:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:34 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 04:56:34 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 04:56:34 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 04:56:34 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 04:56:34 INFO - Downloading docopt-0.6.1.tar.gz 04:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:34 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:34 INFO - Running setup.py install for blobuploader 04:56:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:35 INFO - Running setup.py install for requests 04:56:35 INFO - Running setup.py install for docopt 04:56:35 INFO - Successfully installed blobuploader requests docopt 04:56:35 INFO - Cleaning up... 04:56:35 INFO - Return code: 0 04:56:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:35 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')]} 04:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:35 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')]} 04:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:35 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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:35 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 04:56:35 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 04:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:35 INFO - 'HOME': '/Users/cltbld', 04:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:35 INFO - 'LOGNAME': 'cltbld', 04:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:35 INFO - 'MOZ_NO_REMOTE': '1', 04:56:35 INFO - 'NO_EM_RESTART': '1', 04:56:35 INFO - 'PAGER': '/bin/cat', 04:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:35 INFO - 'PWD': '/builds/slave/test', 04:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:35 INFO - 'SHELL': '/bin/bash', 04:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:35 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:35 INFO - 'USER': 'cltbld', 04:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:35 INFO - 'XPC_FLAGS': '0x0', 04:56:35 INFO - 'XPC_SERVICE_NAME': '0', 04:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-1j7Qsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-kMvL92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Rh3fS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-M59rhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-jYdOWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-JV9Esx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-WN990U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-CnyByH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-1UdKHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:36 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-3j9pYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-s_zEDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-_3Qm9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-0DfneI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-vSlqOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-k9ZARM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-nPvrVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-z0ss3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:56:37 INFO - Running setup.py install for manifestparser 04:56:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:56:37 INFO - Running setup.py install for mozcrash 04:56:38 INFO - Running setup.py install for mozdebug 04:56:38 INFO - Running setup.py install for mozdevice 04:56:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Running setup.py install for mozfile 04:56:38 INFO - Running setup.py install for mozhttpd 04:56:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Running setup.py install for mozinfo 04:56:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Running setup.py install for mozInstall 04:56:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:56:38 INFO - Running setup.py install for mozleak 04:56:39 INFO - Running setup.py install for mozlog 04:56:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:56:39 INFO - Running setup.py install for moznetwork 04:56:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:56:39 INFO - Running setup.py install for mozprocess 04:56:39 INFO - Running setup.py install for mozprofile 04:56:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:56:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:56:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:56:39 INFO - Running setup.py install for mozrunner 04:56:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:56:39 INFO - Running setup.py install for mozscreenshot 04:56:40 INFO - Running setup.py install for moztest 04:56:40 INFO - Running setup.py install for mozversion 04:56:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:56:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:56:40 INFO - Cleaning up... 04:56:40 INFO - Return code: 0 04:56:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:40 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')]} 04:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:40 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')]} 04:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:40 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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:40 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 04:56:40 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 04:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:40 INFO - 'HOME': '/Users/cltbld', 04:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:40 INFO - 'LOGNAME': 'cltbld', 04:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:40 INFO - 'MOZ_NO_REMOTE': '1', 04:56:40 INFO - 'NO_EM_RESTART': '1', 04:56:40 INFO - 'PAGER': '/bin/cat', 04:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:40 INFO - 'PWD': '/builds/slave/test', 04:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:40 INFO - 'SHELL': '/bin/bash', 04:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:40 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:40 INFO - 'USER': 'cltbld', 04:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:40 INFO - 'XPC_FLAGS': '0x0', 04:56:40 INFO - 'XPC_SERVICE_NAME': '0', 04:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:40 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-svnrEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:40 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)) 04:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:40 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-mNj4VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:40 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)) 04:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:40 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-pQgV0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:40 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)) 04:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:40 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ZZibgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-IEqdL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-gHFjk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-eDHiZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Zj0r35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-uPxrzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-CfBw1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-lF1wiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-8Umjvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:41 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)) 04:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:41 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-jUKQQV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:42 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)) 04:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:42 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-A7vLO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:42 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)) 04:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:42 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-KpqoLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:42 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)) 04:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:42 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ZGTR7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:42 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)) 04:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:42 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-8BrYut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:42 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)) 04:56:42 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)) 04:56:42 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)) 04:56:42 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)) 04:56:42 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)) 04:56:42 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)) 04:56:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:43 INFO - Downloading blessings-1.6.tar.gz 04:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:56:43 INFO - Installing collected packages: blessings 04:56:43 INFO - Running setup.py install for blessings 04:56:44 INFO - Successfully installed blessings 04:56:44 INFO - Cleaning up... 04:56:44 INFO - Return code: 0 04:56:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:56:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:44 INFO - Reading from file tmpfile_stdout 04:56:44 INFO - Current package versions: 04:56:44 INFO - blessings == 1.6 04:56:44 INFO - blobuploader == 1.2.4 04:56:44 INFO - docopt == 0.6.1 04:56:44 INFO - functools32 == 3.2.3-2 04:56:44 INFO - jsonschema == 2.5.1 04:56:44 INFO - manifestparser == 1.1 04:56:44 INFO - mozInstall == 1.12 04:56:44 INFO - mozcrash == 1.0 04:56:44 INFO - mozdebug == 0.1 04:56:44 INFO - mozdevice == 0.49 04:56:44 INFO - mozfile == 1.2 04:56:44 INFO - mozhttpd == 0.7 04:56:44 INFO - mozinfo == 0.9 04:56:44 INFO - mozleak == 0.1 04:56:44 INFO - mozlog == 3.4 04:56:44 INFO - moznetwork == 0.27 04:56:44 INFO - mozprocess == 0.25 04:56:44 INFO - mozprofile == 0.28 04:56:44 INFO - mozrunner == 6.13 04:56:44 INFO - mozscreenshot == 0.1 04:56:44 INFO - mozsystemmonitor == 0.3 04:56:44 INFO - moztest == 0.8 04:56:44 INFO - mozversion == 1.4 04:56:44 INFO - psutil == 3.1.1 04:56:44 INFO - requests == 1.2.3 04:56:44 INFO - wsgiref == 0.1.2 04:56:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:44 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')]} 04:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:44 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')]} 04:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:44 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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:44 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 04:56:44 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 04:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:44 INFO - 'HOME': '/Users/cltbld', 04:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:44 INFO - 'LOGNAME': 'cltbld', 04:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:44 INFO - 'MOZ_NO_REMOTE': '1', 04:56:44 INFO - 'NO_EM_RESTART': '1', 04:56:44 INFO - 'PAGER': '/bin/cat', 04:56:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:44 INFO - 'PWD': '/builds/slave/test', 04:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:44 INFO - 'SHELL': '/bin/bash', 04:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:44 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:44 INFO - 'USER': 'cltbld', 04:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:44 INFO - 'XPC_FLAGS': '0x0', 04:56:44 INFO - 'XPC_SERVICE_NAME': '0', 04:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 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)) 04:56:44 INFO - Cleaning up... 04:56:44 INFO - Return code: 0 04:56:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:56:44 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')]} 04:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:44 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')]} 04:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10a747b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a74a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffca1ccab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, '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.Q0BgViBQyY/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.EGjnbAURCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 04:56:44 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 04:56:44 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 04:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:44 INFO - 'HOME': '/Users/cltbld', 04:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:44 INFO - 'LOGNAME': 'cltbld', 04:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:44 INFO - 'MOZ_NO_REMOTE': '1', 04:56:44 INFO - 'NO_EM_RESTART': '1', 04:56:44 INFO - 'PAGER': '/bin/cat', 04:56:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:44 INFO - 'PWD': '/builds/slave/test', 04:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:44 INFO - 'SHELL': '/bin/bash', 04:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:56:44 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:44 INFO - 'USER': 'cltbld', 04:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:44 INFO - 'XPC_FLAGS': '0x0', 04:56:44 INFO - 'XPC_SERVICE_NAME': '0', 04:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:56:45 INFO - Cleaning up... 04:56:45 INFO - Return code: 0 04:56:45 INFO - Running post-action listener: _resource_record_post_action 04:56:45 INFO - Running post-action listener: _start_resource_monitoring 04:56:45 INFO - Starting resource monitoring. 04:56:45 INFO - [mozharness: 2017-05-26 11:56:45.056317Z] Finished create-virtualenv step (success) 04:56:45 INFO - [mozharness: 2017-05-26 11:56:45.057324Z] Running install step. 04:56:45 INFO - Running pre-action listener: _resource_record_pre_action 04:56:45 INFO - Running main action method: install 04:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:45 INFO - Reading from file tmpfile_stdout 04:56:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:56:45 INFO - Reading from file tmpfile_stdout 04:56:45 INFO - Output received: 04:56:45 INFO - Usage: mozinstall [options] installer 04:56:45 INFO - Options: 04:56:45 INFO - -h, --help show this help message and exit 04:56:45 INFO - -d DEST, --destination=DEST 04:56:45 INFO - Directory to install application into. [default: 04:56:45 INFO - "/builds/slave/test"] 04:56:45 INFO - --app=APP Application being installed. [default: firefox] 04:56:45 INFO - mkdir: /builds/slave/test/build/application 04:56:45 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'] 04:56:45 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 04:57:05 INFO - Reading from file tmpfile_stdout 04:57:05 INFO - Output received: 04:57:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:57:05 INFO - Running post-action listener: _resource_record_post_action 04:57:05 INFO - [mozharness: 2017-05-26 11:57:05.543999Z] Finished install step (success) 04:57:05 INFO - [mozharness: 2017-05-26 11:57:05.544167Z] Running run-tests step. 04:57:05 INFO - Running pre-action listener: _resource_record_pre_action 04:57:05 INFO - Running main action method: run_tests 04:57:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:05 INFO - 2017-05-26 04:57:05.559 screenresolution[2285:280820] starting screenresolution argv=screenresolution get 04:57:05 INFO - 2017-05-26 04:57:05.561 screenresolution[2285:280820] Display 0: 1600x1200x32@60 04:57:05 INFO - 2017-05-26 04:57:05.567 screenresolution[2286:280822] starting screenresolution argv=screenresolution list 04:57:05 INFO - Available Modes on Display 0 04:57:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:05 INFO - Intel Iris: 04:57:05 INFO - Chipset Model: Intel Iris 04:57:05 INFO - Type: GPU 04:57:05 INFO - Bus: Built-In 04:57:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:05 INFO - Vendor: Intel (0x8086) 04:57:05 INFO - Device ID: 0x0a2e 04:57:05 INFO - Revision ID: 0x0009 04:57:05 INFO - Displays: 04:57:05 INFO - Display: 04:57:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:05 INFO - Display Serial Number: 1600x1200 60 04:57:05 INFO - Main Display: Yes 04:57:05 INFO - Mirror: Off 04:57:05 INFO - Online: Yes 04:57:05 INFO - Rotation: Supported 04:57:05 INFO - Return code: 0 04:57:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:57:05 INFO - Python 2.7.3 04:57:05 INFO - Return code: 0 04:57:05 INFO - grabbing minidump binary from tooltool 04:57:05 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')]} 04:57:05 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 0x10a525d30>, '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 0x10a44cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a51abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:57:05 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 04:57:05 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 04:57:05 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 04:57:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:57:05 INFO - Return code: 0 04:57:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:57:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:57:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:57:05 INFO - ENV: RUST_BACKTRACE is now 1 04:57:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:57:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:57:05 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/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 04:57:05 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/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --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 04:57:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Q0BgViBQyY/Render', 04:57:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:05 INFO - 'HOME': '/Users/cltbld', 04:57:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:05 INFO - 'LOGNAME': 'cltbld', 04:57:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:57:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:05 INFO - 'MOZ_NO_REMOTE': '1', 04:57:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:57:05 INFO - 'NO_EM_RESTART': '1', 04:57:05 INFO - 'PAGER': '/bin/cat', 04:57:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:05 INFO - 'PWD': '/builds/slave/test', 04:57:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:57:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:05 INFO - 'RUST_BACKTRACE': '1', 04:57:05 INFO - 'SHELL': '/bin/bash', 04:57:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGjnbAURCu/Listeners', 04:57:05 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 04:57:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:05 INFO - 'USER': 'cltbld', 04:57:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:05 INFO - 'XPC_FLAGS': '0x0', 04:57:05 INFO - 'XPC_SERVICE_NAME': '0', 04:57:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:05 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/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 04:57:06 INFO - mozversion application_buildid: 20170526031615 04:57:06 INFO - mozversion application_changeset: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad 04:57:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:57:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:57:06 INFO - mozversion application_name: Firefox 04:57:06 INFO - mozversion application_remotingname: firefox-dev 04:57:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:57:06 INFO - mozversion application_vendor: Mozilla 04:57:06 INFO - mozversion application_version: 54.0 04:57:06 INFO - mozversion platform_buildid: 20170526031615 04:57:06 INFO - mozversion platform_changeset: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad 04:57:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:57:06 INFO - mozversion platform_version: 54.0 04:57:06 INFO - using testdate: 1495799826 04:57:06 INFO - actual date: 1495799826 04:57:06 INFO - starting webserver on 'localhost:49370' 04:57:06 INFO - SUITE-START | Running 2 tests 04:57:06 INFO - TEST-START | dromaeo_css 04:57:06 INFO - operating with platform_type : mac_ 04:57:06 INFO - Initialising browser for dromaeo_css test... 04:57:06 INFO - TEST-INFO | started process 2296 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpIBGnpi/profile http://localhost:49370/getInfo.html) 04:57:08 INFO - PROCESS | 2296 | __metrics Screen width/height:1600/1200 04:57:08 INFO - PROCESS | 2296 | colorDepth:24 04:57:08 INFO - PROCESS | 2296 | Browser inner width/height: 1024/689 04:57:08 INFO - PROCESS | 2296 | __metrics 04:57:08 INFO - PROCESS | 2296 | 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" 04:57:08 INFO - PROCESS | 2296 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:57:09 INFO - TEST-INFO | 2296: exit 0 04:57:09 INFO - Browser initialized. 04:57:09 INFO - Running cycle 1/1 for dromaeo_css test... 04:57:09 INFO - TEST-INFO | started process 2299 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpIBGnpi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:21 INFO - PROCESS | 2299 | RSS: Main: 193921024 04:57:21 INFO - PROCESS | 2299 | 05:00:25 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49370/tests/dromaeo/cssquery-ext.html) 05:00:27 INFO - PROCESS | 2299 | RSS: Main: 313270272 05:00:27 INFO - PROCESS | 2299 | 05:03:30 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-ext.html (next: http://localhost:49370/tests/dromaeo/cssquery-jquery.html) 05:03:30 INFO - PROCESS | 2299 | RSS: Main: 321724416 05:03:30 INFO - PROCESS | 2299 | 05:06:33 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49370/tests/dromaeo/cssquery-mootools.html) 05:06:33 INFO - PROCESS | 2299 | RSS: Main: 311353344 05:06:33 INFO - PROCESS | 2299 | 05:09:35 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49370/tests/dromaeo/cssquery-prototype.html) 05:09:36 INFO - PROCESS | 2299 | RSS: Main: 318300160 05:09:36 INFO - PROCESS | 2299 | 05:12:39 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49370/tests/dromaeo/cssquery-yui.html) 05:12:40 INFO - PROCESS | 2299 | RSS: Main: 298192896 05:12:40 INFO - PROCESS | 2299 | 05:15:43 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49370/tests/dromaeo/cssquery-yui.html (next: http://localhost:49370/tests/dromaeo/cssquery-dojo.html) 05:15:44 INFO - PROCESS | 2299 | RSS: Main: 466845696 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | __start_tp_report 05:15:44 INFO - PROCESS | 2299 | _x_x_mozilla_page_load 05:15:44 INFO - PROCESS | 2299 | _x_x_mozilla_page_load_details 05:15:44 INFO - PROCESS | 2299 | |i|pagename|runs| 05:15:44 INFO - PROCESS | 2299 | |0;dojo.html;3387.441072223083;3421.794692318461;3426.3318652862686;3459.6540345965423;3483.4078206704858;5942.67315297659;5956.910646340298;5982.34194238634;5983.311919129296;6032.69836508175;5917.408259174081;5963.4334738199805;5967.283925928884;5996.100584912266;6002.609830361034;4542.750148741818;5284.101702710539;5326.280952071459;5339.733013349317;5392.956625547151;5265.447128051557;5267.0256002639635;5285.101532739443;5341.359036915575;5444.3194600674915;5332.546733527654;5345.866353341159;5351.973240133794;5352.357717073956;5384.596155288349;5430.3755068167275;5435.211894275328;5457.508824205841;5458.836234912959;5474.945250547484;5288.656237344599;5383.16560933056;5387.111126664135;5400.891982160335;5423.078076726966;5703.914441283384;5755;5988.221366381419;6000.96999515004;6069.939300607026;6202.534809889231;6256.436920677161;6257.780977665805;6309.810705678837;6341.080543321193;6336.081268216129;6351.364863513611;6405.19935008124;6407;6414.550981431302;5615.747291371898;5966.910496342558;6101.51187904971;6102.877942441126;6123.418275263843;6178;6211.503079753609;6381.617102973836;6420;6520.413162815369;6302.464290535264;6314.71583778731;6317.842053948596;6325.209348831396;6326.778562750282;6340;6352.301246862841;6378.74485020594;6392.3927226913365;6394.776182833579;5120.769565369567;6182.350853160443;6252.468540174045;6332.746690132343;6368.649724265118;6231.096491008779;6266.436020758154;6269.247690277196;6275;6288.717007734663;4872.171730805771;4881.804727810848;4891.559759621651;4898.314236006961;4919.1391506486225;4378.846740364072;4413.867583972486;4422.646188304992;4430.113977204572;4446.26636604963;5568.693721845338;5626.85932851682;5680.147977803363;5735.598508104476;5789.131630255411;6381.744730210832;6385.45723613498;6389.329120442287;6393;6393.904091438632;5814.360420353758;5819.15622234782;5831.329397119301;5834.387389324144;5852.473277405054;5291.867703307371;5317.042932272151;5410.242566040757;5425.891482170335;5532.806351777668;6321.399467050623;6378.936210637834;6381.904271436025;6384.968075159594;6484.773032943825;6380.553361264667;6397.296297407374;6399.200099987502;6402.167718196604;6417.454516366161;6602.9009564855605;6605.834854128685;6606.8017959461295;6618.90071648916;6618.966905165444;6155.35368786271;6353.36466353361;6356.77751278703;6357.046443033582;6360.077788720702;6263.3736626337;6268.341824108403;6269.467095296951;6270.874582508233;6292.119103325446;6129.695011270001;6289.08808222796;6297.4017468340435;6335.746570137143;6360.650164241011;6297.496200304056;6310.495160387066;6310.652914089769;6324.588901721373;6330.113784070142;5930.140129681258;5964.105384192232;6222.688865556795;6226.221722284715;6241;6195.969020155051;6237.968810156101;6285.151504546828;6287.559870808999;6315.368463153648;6146.801373732079;6159.476444502267;6173.876522469615;6184.628922264678;6188.226471691039 05:15:44 INFO - PROCESS | 2299 | |1;ext.html;786.5870418030534;823.246729242743;885.274075258288;892.1257167975388;900.9594568244424;41184;42114.36828447576;43757.12485750283;43765.99999999996;43815.56184438155;42908.78545607276;43083.138337233235;43088.99999999992;43159.921001974886;43532.782336088356;7590.924090759105;7778.766637000871;9507.714768556954;9666.565004574775;9686.225101991859;11360.545578176863;11656.358900260482;11762.17664763467;11773;11798.351090690008;11556.47995840185;11630.651080467556;11639.35983520906;11640.010599099087;11688.240264382788;10651.84022239667;10725.498430219775;11528.65414037576;11556.959873611331;11584.247023943417;11379.431028448627;11436.542538298458;11439.199256051996;11450.770984580262;11459.484323205392;40778;40988;41116.58883411157;41125;41127.58872411268;3922.411638254256;3934.7245692801516;3992.121733218687;4002.6597739192202;4037.1118353962083;27495.725042749516;28120.578191327146;29538;30046;30258.092257232318;2496.6504689343506;3056.2664960409343;3132.0447263584597;3277.6394596594355;3324.1024923270825;347697.9999999975;348469;349209;349336.99999999744;349919.2504037489;54214.728926355114;54852;54882.17676734852;55057.449425505234;55141.72429137828;56717.582060447996;56774;56816;56846.43153568411;56889.71555142198;14990.62523436901;15267.389304427921;15280.083195008336;15301.6174595636;15321.6169595761;12979.41592628334;12990.350482476028;12996.870031299755;13000.934995324962;13014.869851301366;12491.687707807196;13000.699930006924;13001.544945926848;13019.088663793633;13125.212487250605;5609.607327487037;5620.747066382022;5638.097904335369;5650.519705825051;5700.144978253295;855.2986551028097;931.9860202096974;932.7361425268799;959.856021596766;980.0885176785515;27392.863035684695;28301.292467688472;28469.430611387656;28923.132306030853;29090;1050.9474526273655;1055.2560444886337;1076.9730756731144;1094.9452527373758;1103.9889601104046;13144.27706476153;14503.202323872289;14706.632334191518;14741.852581474262;14909.850901491063;412825;413274;413779;414171;414244;57286.56783580444;57332.71333643389;57385.42614573884;57453.13820292699;57565;1407.936642851074;1450.4633285684365;1453.1499073042178;1453.6874571967078;1456.5775924981638;19540.8045919539;26590.7340926596;28461.430771384043;28709.56935645925;29020;46692.76653616846;46793.76603116962;46909;46965.53034469612;47013;406399;474461;477067;477173;477195;24470.510589787747;25046.62430063514;30068.947586834354;36374;39479.01302467461;47314.763426182646;49055.75472122616;49281.26078108903;49790.004199916526;49829.00341993212;32545.349093017532;32600.347993039533;33113;33433.33133337271;33708.49437258394;32536.83731581327;32587.02238932836;32709.672903270664;33279.334413312085;33600.327993440485;40311.99220019406;40375.39436908507;40559.18881622292;42056.57943420527;43229.783851080545;39978.60021399749;40178;41482.58517414787;41829.58170418379;41956.79021604872;31511;32180.517292240165;32321.191970200933;32650.512115913065;32729.672703272663 05:15:44 INFO - PROCESS | 2299 | |2;jquery.html;353.82662495377275;427.24377851203366;430.09104711364836;432.33420532380137;441.9712718673286;20819.687704684424;21452.463688407777;22104.447388815286;22775;23018.19436319729;22361.664575031347;22497.662535061947;22847.885760571175;23176.957036933338;23510.17714379997;6236.81500514903;6539.215294164707;6726.6972986215615;6963.582185068887;6973.058637083979;6799.796006119825;6802.08171896795;6843.965780171092;6864.622445765481;6959;6564.1138446309615;6727.798166055026;6765.424938880201;6784.626845523519;6857.314268573127;6507.219133703986;6678.36555527229;6683.799486015428;6779.966100169492;6781.186257649089;6637.834054148637;6640.867182656368;6644.501662375293;6646.833829154261;6654.168228971379;23591.174308899277;24470.877645611836;26707;26830.195094147206;27142;2977.8607735248024;2997.1308320587004;3011.864466099009;3018.26052617108;3031.8332491712945;15988.280527376295;16599.66800663992;16663.16684165787;16877.493675189762;17009.914950425293;2070.188297988253;2095.2399884702086;2140.2723074154706;2163.772803855604;2210.2264207527396;126894;130019.34990325083;132413;132597;133360.6663933358;19499.44004479638;21437.67843482349;22285.77714222837;22452;22952;33449.66550334514;35693;35779.82110089485;36507.26985460276;36683.81658091746;8663.740087797376;10303.278770485995;10441.425721585234;10627.734306642396;10637.840432393521;8677.284525060912;8747;8748.8687669685;8905.51019693923;8959.790428292228;9521.666741664008;9577.156501840558;9766.316357854881;9791.363561368631;9806.12658000086;4157.334826427757;4267.573242675708;4292.59220374064;4295.9904422460695;4304.20372231132;956.53129966316;966.8259713251541;984.5471083301618;992.4541502173776;1025.3847691385108;22751.317460476213;23035.654465183037;23040.53918921612;23051.308460746204;23186.304410867702;930.874331965176;990.9702708918744;993.8509223616516;1014.9441780702132;1020.2093377632245;10574.15406767472;11453.770924581462;11887.762244755057;12201.14591978553;12218.633440996784;742300;744545;744713;761341;774892;74908;83772;99186.51220231703;125346.37326813307;129985.70014299736;1268.866768989243;1309.6594885329691;1331.4940322677128;1342.9664258393618;1345.5895951734815;20511.12844201783;20879.8956005219;21124.943752812604;21128.260510882046;21444.46388840229;49956.750216248685;55353.44646553483;55464.44535554754;55467.445325547844;56496.43503564912;75160.24839751533;75768;77298.4540309202;77591;78390;11609.535618575163;11772.764544709229;12015.819762703391;12253.264804111783;12297.692557686129;13198.14212076212;13209.933950330571;13294.800577991531;13369.465221391036;13636.181829090287;9189.791321137815;11271.830922536004;11346.773064538498;11370.431478426211;11659.18385712992;10597.682069537927;10621.468926553796;10706.304090234109;10824.4046577439;11043.723906902393;11599.420028998686;11665.533378664759;11702.70743231426;11801.468933897824;11814.054875610102;11804;11845.348505832262;12086.939565302117;12178.57374991871;12192.817107743569;11293.943530282624;11376.715582110513;11514.82727759101;11543.942280288546;11692.47383867745 05:15:44 INFO - PROCESS | 2299 | |3;mootools.html;1491.6718321969167;1578.4948816378762;1580.6169601598601;1609.4527860527426;1614.8869579129466;10604;10739.516721747521;11309.151813613971;11313.264637798547;11313.377764222965;10016.799664006716;10866.67399978;11218;11288.661340159808;11297.265677730942;2325.197806756666;2369.917052903149;2372.644103384489;2378.0250097460043;2381.273711517986;2264.6603009548617;2326.406766274606;2353.9293821185306;2360.7639236076425;2363.9763602364014;2279.1453205048106;2344.882755862209;2345.870977096259;2355.9411014724596;2362.9645555316683;2288.1533832481923;2302.861828290308;2329.440934175794;2338.8479748816358;2343.308723926446;2349.119080344871;2352.2002519143575;2358.4221865642858;2362.350353652759;2367.846090004144;11105.888941110567;11201.663950081429;11209.495572699248;11220.551177952872;11220.607278745205;5376.650517716336;5430.619856610039;5608.635438696471;5622.522085622686;5626.634268772516;3294.8846790362463;3422.3326451342;3460.186856088817;3501.8074005929666;3523.7004854587135;1102.2504696806168;1130.2257953302014;1164.889335513125;1171.3264872698148;1197.8442802435627;15456.381744730197;15476;15546.533603991898;15575.76636350467;15611.921940390339;4198.181354635817;4430;4483.596476317082;4494.865154045384;4530.841420550265;11574.826377604342;11600.94199528997;11608;11619.01238394729;11639.777823328597;5533.833984980457;5547.472990065938;5548.9722551386985;5555.77776888928;5562.221289019541;4974.129527332702;4977;4981.9252711209365;4995.150824359867;5076.213186956028;3146.363890776829;3580.4271316589743;3643.763155394944;3660.762050466711;3686.8156592170285;915.5285028210477;927.0682963621517;931.4085555672197;932.6455946740331;939.0093451408829;961.9086186812242;965.8937516873139;976.9316147869581;986.9259805514472;1009.4498498318434;6609.966950165219;6713.328667133248;6808.8978665320055;6903.240643529208;6926.722931082701;878.5124256037833;882.298572634757;971.155095067296;997.4514017290461;1037.9429131397847;3678.429843374281;4114.362273847559;4159.771212583277;4331.415258940003;4491.865244042684;13183.604491865259;13897.305134743223;13922.582322530137;13955.441782328593;13958.162510249214;13513.256770877499;13561.796573051412;13707.657308567366;13869.930650346683;13890.236037018061;10186.388816671024;10235.948820255851;10310.762708475031;10331.966803319758;10346.172306215485;5852.770918107245;6237.064440333987;6567;6681.1314529110905;6739.898901516579;12178.817317740419;12184.20802647825;12272.509099635914;12281.01751859867;12291.69270768238;12523.74952500963;12539.623811285675;12688.17526860751;12726.427310771223;12777.808332874829;8463.026751923362;8696.956515217384;8697.95651021741;8711.477311361085;8715.95408550978;10007.349522281029;10297.794044119226;10335.586576536854;10342.638007669695;10360.222983276133;8656.740297791077;8788.033316335326;8881.511516866376;8919.643214271357;8994.505302208441;8645.092265312052;8700.477971321741;8705.26005289532;8791.604377802887;8798.780030499289;7880;8081.9191808083515;8088.150744172012;8091.716789912325;8104.149064348158;5830.5044071254415;5903.2325797646035;5947.672877991752;5983.790567330123;6128;5955.67243801595;6064.969675151596;6112.052631842072;6173.783917562864;6177.351378105234 05:15:44 INFO - PROCESS | 2299 | |4;prototype.html;356.3798989757821;368.92068205335846;384.66918450132897;385.7454080306998;390.0560643243352;4061.9535348488635;4732.763361831903;4802.879928001802;5034.521720436562;5073.391193056834;4205.915881682365;4242.787860606965;4791.771826100862;4909.33723947267;4934.420985284711;3567.1807378237154;3809.6380843819793;4125.814338354766;4278.764667943262;4296.935545966805;3633.6366363363586;3750.6999440044733;3919.7652739387145;4070.0085437203634;4363.869083927471;3630.455431685255;4134.635570261807;4137.338025915854;4249.024203745258;4310.4611923509565;3630.3679832121766;3719.20037192005;3755.699544036484;4249.192653395876;4300.6989510734265;3766.397376419787;3938.921221575581;4116.9794151029355;4133.276676581616;4178.979105104486;3939.740446503921;4284.507281662586;4760.785764640599;4784.392506084192;4902.970376220997;4561.833129263573;4637.211674015425;4644.419447569054;4678.672492925497;4899.265110233458;4719.528047195287;5058.064258112261;5292.497212764782;5335.653182543122;5353.812616558402;3872.9031774205587;4040.878773636796;4066.8373265069363;4323.891902702426;4613.192691279013;4788.880277993008;5371.382291036542;5387.73388253764;5613.298337707786;5626.324841019104;4693.404242557547;4706.070431189926;4766.737829419346;4770.976145119253;4788.976055119702;4577.496475387705;4730.95269047312;4776.450708168605;4806.94247265601;4876.3183796234325;4133.441985331941;4397.494288156903;4429.844955426544;4439.556044395535;4913.434954980223;4234.978825105854;5213.983273261753;5297.761600727977;5306.867328316745;5316.245638939218;1344.2740919903338;1386.5008596905107;1423.5444657709434;1496.3790027138616;1505.5784380373514;1179.7994340962057;1206.6078524479578;1277.482619539088;1335.1455068756004;1340.5710172744627;4224.936625950613;4301.720388174758;4498.8650340489185;4610.400647915817;4634.980304333041;4491.136178485892;5064.848054558369;5390.299261096033;5434.538064264581;5436.673799572039;4161.375793630979;4478.977605111953;4685.742284174335;4694.647901407408;4721.929171062437;2197.5385169114666;2703.513367593852;2724.6594175728032;2820.801159507201;2831.3912508810704;4266.488021437447;4870.561649451566;5044.445111037783;5512.834614961477;5597.776088956396;5242.659227150223;5505.366882808528;5525.778968841201;5526.336839579276;5526.530244929146;5581.609287349927;5583.553315734732;5595.922242766462;5599.496045355938;5626.634268772516;4850;5019.954945280117;5127.41034781005;5298.496642818926;5419.160030195326;4215.283401821636;4449.532799056053;4505.414296141481;4991.9500804991485;5249.687578105473;5556.666600004012;5560.663601838864;5582.3859375467855;5590.875467322554;5618.831435056954;4092.7339722919105;4201.768902710381;4605.555638808566;4710.034442939121;4733.408323959505;4953.356063711694;4962.751862406793;5116;5184.029081138766;5499.697516636624;4628.4445866496235;4687.835925742583;5113.105206588906;5126.974365128151;5301.045811753767;4810.254410566367;4950.851474455772;5023.8492845214705;5093.205561856441;5316.494932981361;4510.2783554631105;4989.276554899592;5176.767045483038;5211.296474975829;5236.952609478043;4894.241392584013;4972.801087956586;5242.868928276824;5314.574834013192;5331.29475014861;4825.469198388104;5160.767765450489;5199.584033277404;5219.582433405394;5222.3472065991755 05:15:44 INFO - PROCESS | 2299 | |5;yui.html;732.3591857125016;746.9290417410348;782.3154739602847;841.5077179849787;860.8020155364268;15783.36866525339;16611.750823737653;16628.334866605335;16629.83370166298;16669.749953750674;12564.81152782709;15130;15200;15264.084154950711;15285.388584456608;959.6976952260051;970.6602689058843;977.5942983661807;981.7496538382702;1002.6039714312842;964.4358050540442;983.650803964593;985.2265971212599;986.3145114145672;988.298308201178;1043.8068957242897;1045.8640376751011;1047.607147319755;1047.8690163729534;1048.2190767877942;1039.552992213348;1040.3081950502906;1041.208681402136;1043.0143514378915;1043.1498328861992;772.6136931534232;773.2190487607501;773.2576726342718;774.3069952392577;774.6939958716332;16736.41422550205;16752.329906803712;16796.83203167965;17278.740818887727;17508.47474575752;14481.348339324755;14531.78202326966;14535.927320363437;14568.344424500923;14668.633284167874;11857.584984525587;11990.801576872575;11991.700207494796;12038.518459261619;12482.188657737308;3681.852725890961;3786.8674596388996;3802.1445174835717;3802.9619703802887;3947.3684210526176;114406;116434;119428.80571194264;121309.78690213073;121760.56478870462;10345;10374.948125259325;10567.313124646873;10750.247482676294;10794.78410431787;23020.4244893879;23049.308520744406;23051.7694823053;23066.769332306798;23077;6501.512386570931;7829.3345065670055;8080.717174898968;8090.676372945016;8167.550784706779;4378.1900348435;4518;4554.997900461893;4577.153226653079;4601.792919318638;3314.784539004957;3332.3835090508333;3358.714509266691;3383.9661603384143;3386.3227354529195;596.0850095103995;596.6241268001131;611.7247238742584;619.3713380918372;619.7613918641265;560.7812952948353;712.8279157748042;720.2221600671262;725.7543371278086;730.2332550821616;10293.794124117476;10427.635032773816;10499.737506562244;10507.684769456928;10691.518881650345;700.6111608057577;713.4934196720386;719.7229066809316;719.9856002879914;739.2644318902619;4828.565429111397;5091.541761241506;5097.668651537638;5107.208382700687;5115.462876397999;90915.54542227379;101302.49348753209;102666.94666106637;105793;107406;66166.33833661601;77148;77634.2236577627;82869.58565207256;86926.56536717403;56786.14820777609;68427;69347.30652693409;74750.6262468695;75679;8847.42491738035;11092.11263098934;11192.664220073411;11351.14866385041;11378.260413073123;39182;47589;47733.52266477291;49858.75070624769;49883.50116498789;61731.765364693354;62439.8780060986;63832.36167638264;64559;64861.70276594536;10809.729756756144;10818.945905270422;10997.580532282886;12798.296093714935;13576.117552359065;12085.81871271914;12380.4428800706;12964.675883102998;13188.208707477583;13291.401886915306;11566.421678915853;11825.763484730429;12080.75838483243;12129.272243665408;12336.198147120409;11596.420178990847;11884.286942783461;12239.632811015685;12304.384780761106;12430.751384972069;12970;13471.663208419868;13938.93030534841;14377.496787612383;14378;8907.752914592093;9201.447913125234;9649.758756031155;9852.359596626484;10302.969703029637;9880.802383952137;10117.443540605338;10378.38916465018;11395.542522579226;11604.593839215924 05:15:44 INFO - PROCESS | 2299 | __end_tp_report 05:15:44 INFO - PROCESS | 2299 | __start_cc_report 05:15:44 INFO - PROCESS | 2299 | _x_x_mozilla_cycle_collect,2071 05:15:44 INFO - PROCESS | 2299 | __end_cc_report 05:15:44 INFO - PROCESS | 2299 | __startTimestamp1495800944024__endTimestamp 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | ------- Summary: start ------- 05:15:44 INFO - PROCESS | 2299 | Number of tests: 6 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | [#0] dojo.html Cycles:165 Average:5869.67 Median:6173.88 stddev:674.29 (10.9%) stddev-sans-first:647.63 05:15:44 INFO - PROCESS | 2299 | Values: 3387.4 3421.8 3426.3 3459.7 3483.4 5942.7 5956.9 5982.3 5983.3 6032.7 5917.4 5963.4 5967.3 5996.1 6002.6 4542.8 5284.1 5326.3 5339.7 5393.0 5265.4 5267.0 5285.1 5341.4 5444.3 5332.5 5345.9 5352.0 5352.4 5384.6 5430.4 5435.2 5457.5 5458.8 5474.9 5288.7 5383.2 5387.1 5400.9 5423.1 5703.9 5755.0 5988.2 6001.0 6069.9 6202.5 6256.4 6257.8 6309.8 6341.1 6336.1 6351.4 6405.2 6407.0 6414.6 5615.7 5966.9 6101.5 6102.9 6123.4 6178.0 6211.5 6381.6 6420.0 6520.4 6302.5 6314.7 6317.8 6325.2 6326.8 6340.0 6352.3 6378.7 6392.4 6394.8 5120.8 6182.4 6252.5 6332.7 6368.6 6231.1 6266.4 6269.2 6275.0 6288.7 4872.2 4881.8 4891.6 4898.3 4919.1 4378.8 4413.9 4422.6 4430.1 4446.3 5568.7 5626.9 5680.1 5735.6 5789.1 6381.7 6385.5 6389.3 6393.0 6393.9 5814.4 5819.2 5831.3 5834.4 5852.5 5291.9 5317.0 5410.2 5425.9 5532.8 6321.4 6378.9 6381.9 6385.0 6484.8 6380.6 6397.3 6399.2 6402.2 6417.5 6602.9 6605.8 6606.8 6618.9 6619.0 6155.4 6353.4 6356.8 6357.0 6360.1 6263.4 6268.3 6269.5 6270.9 6292.1 6129.7 6289.1 6297.4 6335.7 6360.7 6297.5 6310.5 6310.7 6324.6 6330.1 5930.1 5964.1 6222.7 6226.2 6241.0 6196.0 6238.0 6285.2 6287.6 6315.4 6146.8 6159.5 6173.9 6184.6 6188.2 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | [#1] ext.html Cycles:180 Average:56673.17 Median:349273.00 stddev:108680.60 (31.1%) stddev-sans-first:108904.47 05:15:44 INFO - PROCESS | 2299 | Values: 786.6 823.2 885.3 892.1 901.0 41184.0 42114.4 43757.1 43766.0 43815.6 42908.8 43083.1 43089.0 43159.9 43532.8 7590.9 7778.8 9507.7 9666.6 9686.2 11360.5 11656.4 11762.2 11773.0 11798.4 11556.5 11630.7 11639.4 11640.0 11688.2 10651.8 10725.5 11528.7 11557.0 11584.2 11379.4 11436.5 11439.2 11450.8 11459.5 40778.0 40988.0 41116.6 41125.0 41127.6 3922.4 3934.7 3992.1 4002.7 4037.1 27495.7 28120.6 29538.0 30046.0 30258.1 2496.7 3056.3 3132.0 3277.6 3324.1 347698.0 348469.0 349209.0 349337.0 349919.3 54214.7 54852.0 54882.2 55057.4 55141.7 56717.6 56774.0 56816.0 56846.4 56889.7 14990.6 15267.4 15280.1 15301.6 15321.6 12979.4 12990.4 12996.9 13000.9 13014.9 12491.7 13000.7 13001.5 13019.1 13125.2 5609.6 5620.7 5638.1 5650.5 5700.1 855.3 932.0 932.7 959.9 980.1 27392.9 28301.3 28469.4 28923.1 29090.0 1050.9 1055.3 1077.0 1094.9 1104.0 13144.3 14503.2 14706.6 14741.9 14909.9 412825.0 413274.0 413779.0 414171.0 414244.0 57286.6 57332.7 57385.4 57453.1 57565.0 1407.9 1450.5 1453.1 1453.7 1456.6 19540.8 26590.7 28461.4 28709.6 29020.0 46692.8 46793.8 46909.0 46965.5 47013.0 406399.0 474461.0 477067.0 477173.0 477195.0 24470.5 25046.6 30068.9 36374.0 39479.0 47314.8 49055.8 49281.3 49790.0 49829.0 32545.3 32600.3 33113.0 33433.3 33708.5 32536.8 32587.0 32709.7 33279.3 33600.3 40312.0 40375.4 40559.2 42056.6 43229.8 39978.6 40178.0 41482.6 41829.6 41956.8 31511.0 32180.5 32321.2 32650.5 32729.7 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | [#2] jquery.html Cycles:180 Average:41017.38 Median:23181.63 stddev:124042.55 (535.1%) stddev-sans-first:124352.94 05:15:44 INFO - PROCESS | 2299 | Values: 353.8 427.2 430.1 432.3 442.0 20819.7 21452.5 22104.4 22775.0 23018.2 22361.7 22497.7 22847.9 23177.0 23510.2 6236.8 6539.2 6726.7 6963.6 6973.1 6799.8 6802.1 6844.0 6864.6 6959.0 6564.1 6727.8 6765.4 6784.6 6857.3 6507.2 6678.4 6683.8 6780.0 6781.2 6637.8 6640.9 6644.5 6646.8 6654.2 23591.2 24470.9 26707.0 26830.2 27142.0 2977.9 2997.1 3011.9 3018.3 3031.8 15988.3 16599.7 16663.2 16877.5 17009.9 2070.2 2095.2 2140.3 2163.8 2210.2 126894.0 130019.3 132413.0 132597.0 133360.7 19499.4 21437.7 22285.8 22452.0 22952.0 33449.7 35693.0 35779.8 36507.3 36683.8 8663.7 10303.3 10441.4 10627.7 10637.8 8677.3 8747.0 8748.9 8905.5 8959.8 9521.7 9577.2 9766.3 9791.4 9806.1 4157.3 4267.6 4292.6 4296.0 4304.2 956.5 966.8 984.5 992.5 1025.4 22751.3 23035.7 23040.5 23051.3 23186.3 930.9 991.0 993.9 1014.9 1020.2 10574.2 11453.8 11887.8 12201.1 12218.6 742300.0 744545.0 744713.0 761341.0 774892.0 74908.0 83772.0 99186.5 125346.4 129985.7 1268.9 1309.7 1331.5 1343.0 1345.6 20511.1 20879.9 21124.9 21128.3 21444.5 49956.8 55353.4 55464.4 55467.4 56496.4 75160.2 75768.0 77298.5 77591.0 78390.0 11609.5 11772.8 12015.8 12253.3 12297.7 13198.1 13209.9 13294.8 13369.5 13636.2 9189.8 11271.8 11346.8 11370.4 11659.2 10597.7 10621.5 10706.3 10824.4 11043.7 11599.4 11665.5 11702.7 11801.5 11814.1 11804.0 11845.3 12086.9 12178.6 12192.8 11293.9 11376.7 11514.8 11543.9 11692.5 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | [#3] mootools.html Cycles:180 Average:6715.72 Median:2379.65 stddev:4278.04 (179.8%) stddev-sans-first:4272.03 05:15:44 INFO - PROCESS | 2299 | Values: 1491.7 1578.5 1580.6 1609.5 1614.9 10604.0 10739.5 11309.2 11313.3 11313.4 10016.8 10866.7 11218.0 11288.7 11297.3 2325.2 2369.9 2372.6 2378.0 2381.3 2264.7 2326.4 2353.9 2360.8 2364.0 2279.1 2344.9 2345.9 2355.9 2363.0 2288.2 2302.9 2329.4 2338.8 2343.3 2349.1 2352.2 2358.4 2362.4 2367.8 11105.9 11201.7 11209.5 11220.6 11220.6 5376.7 5430.6 5608.6 5622.5 5626.6 3294.9 3422.3 3460.2 3501.8 3523.7 1102.3 1130.2 1164.9 1171.3 1197.8 15456.4 15476.0 15546.5 15575.8 15611.9 4198.2 4430.0 4483.6 4494.9 4530.8 11574.8 11600.9 11608.0 11619.0 11639.8 5533.8 5547.5 5549.0 5555.8 5562.2 4974.1 4977.0 4981.9 4995.2 5076.2 3146.4 3580.4 3643.8 3660.8 3686.8 915.5 927.1 931.4 932.6 939.0 961.9 965.9 976.9 986.9 1009.4 6610.0 6713.3 6808.9 6903.2 6926.7 878.5 882.3 971.2 997.5 1037.9 3678.4 4114.4 4159.8 4331.4 4491.9 13183.6 13897.3 13922.6 13955.4 13958.2 13513.3 13561.8 13707.7 13869.9 13890.2 10186.4 10235.9 10310.8 10332.0 10346.2 5852.8 6237.1 6567.0 6681.1 6739.9 12178.8 12184.2 12272.5 12281.0 12291.7 12523.7 12539.6 12688.2 12726.4 12777.8 8463.0 8697.0 8698.0 8711.5 8716.0 10007.3 10297.8 10335.6 10342.6 10360.2 8656.7 8788.0 8881.5 8919.6 8994.5 8645.1 8700.5 8705.3 8791.6 8798.8 7880.0 8081.9 8088.2 8091.7 8104.1 5830.5 5903.2 5947.7 5983.8 6128.0 5955.7 6065.0 6112.1 6173.8 6177.4 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | [#4] prototype.html Cycles:180 Average:4407.48 Median:4731.86 stddev:1202.40 (25.4%) stddev-sans-first:1166.69 05:15:44 INFO - PROCESS | 2299 | Values: 356.4 368.9 384.7 385.7 390.1 4062.0 4732.8 4802.9 5034.5 5073.4 4205.9 4242.8 4791.8 4909.3 4934.4 3567.2 3809.6 4125.8 4278.8 4296.9 3633.6 3750.7 3919.8 4070.0 4363.9 3630.5 4134.6 4137.3 4249.0 4310.5 3630.4 3719.2 3755.7 4249.2 4300.7 3766.4 3938.9 4117.0 4133.3 4179.0 3939.7 4284.5 4760.8 4784.4 4903.0 4561.8 4637.2 4644.4 4678.7 4899.3 4719.5 5058.1 5292.5 5335.7 5353.8 3872.9 4040.9 4066.8 4323.9 4613.2 4788.9 5371.4 5387.7 5613.3 5626.3 4693.4 4706.1 4766.7 4771.0 4789.0 4577.5 4731.0 4776.5 4806.9 4876.3 4133.4 4397.5 4429.8 4439.6 4913.4 4235.0 5214.0 5297.8 5306.9 5316.2 1344.3 1386.5 1423.5 1496.4 1505.6 1179.8 1206.6 1277.5 1335.1 1340.6 4224.9 4301.7 4498.9 4610.4 4635.0 4491.1 5064.8 5390.3 5434.5 5436.7 4161.4 4479.0 4685.7 4694.6 4721.9 2197.5 2703.5 2724.7 2820.8 2831.4 4266.5 4870.6 5044.4 5512.8 5597.8 5242.7 5505.4 5525.8 5526.3 5526.5 5581.6 5583.6 5595.9 5599.5 5626.6 4850.0 5020.0 5127.4 5298.5 5419.2 4215.3 4449.5 4505.4 4992.0 5249.7 5556.7 5560.7 5582.4 5590.9 5618.8 4092.7 4201.8 4605.6 4710.0 4733.4 4953.4 4962.8 5116.0 5184.0 5499.7 4628.4 4687.8 5113.1 5127.0 5301.0 4810.3 4950.9 5023.8 5093.2 5316.5 4510.3 4989.3 5176.8 5211.3 5237.0 4894.2 4972.8 5242.9 5314.6 5331.3 4825.5 5160.8 5199.6 5219.6 5222.3 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - PROCESS | 2299 | [#5] yui.html Cycles:180 Average:20063.47 Median:23050.54 stddev:29091.85 (126.2%) stddev-sans-first:29137.25 05:15:44 INFO - PROCESS | 2299 | Values: 732.4 746.9 782.3 841.5 860.8 15783.4 16611.8 16628.3 16629.8 16669.7 12564.8 15130.0 15200.0 15264.1 15285.4 959.7 970.7 977.6 981.7 1002.6 964.4 983.7 985.2 986.3 988.3 1043.8 1045.9 1047.6 1047.9 1048.2 1039.6 1040.3 1041.2 1043.0 1043.1 772.6 773.2 773.3 774.3 774.7 16736.4 16752.3 16796.8 17278.7 17508.5 14481.3 14531.8 14535.9 14568.3 14668.6 11857.6 11990.8 11991.7 12038.5 12482.2 3681.9 3786.9 3802.1 3803.0 3947.4 114406.0 116434.0 119428.8 121309.8 121760.6 10345.0 10374.9 10567.3 10750.2 10794.8 23020.4 23049.3 23051.8 23066.8 23077.0 6501.5 7829.3 8080.7 8090.7 8167.6 4378.2 4518.0 4555.0 4577.2 4601.8 3314.8 3332.4 3358.7 3384.0 3386.3 596.1 596.6 611.7 619.4 619.8 560.8 712.8 720.2 725.8 730.2 10293.8 10427.6 10499.7 10507.7 10691.5 700.6 713.5 719.7 720.0 739.3 4828.6 5091.5 5097.7 5107.2 5115.5 90915.5 101302.5 102666.9 105793.0 107406.0 66166.3 77148.0 77634.2 82869.6 86926.6 56786.1 68427.0 69347.3 74750.6 75679.0 8847.4 11092.1 11192.7 11351.1 11378.3 39182.0 47589.0 47733.5 49858.8 49883.5 61731.8 62439.9 63832.4 64559.0 64861.7 10809.7 10818.9 10997.6 12798.3 13576.1 12085.8 12380.4 12964.7 13188.2 13291.4 11566.4 11825.8 12080.8 12129.3 12336.2 11596.4 11884.3 12239.6 12304.4 12430.8 12970.0 13471.7 13938.9 14377.5 14378.0 8907.8 9201.4 9649.8 9852.4 10303.0 9880.8 10117.4 10378.4 11395.5 11604.6 05:15:44 INFO - PROCESS | 2299 | -------- Summary: end -------- 05:15:44 INFO - PROCESS | 2299 | 05:15:44 INFO - TEST-INFO | 2299: exit 0 05:15:44 INFO - TEST-OK | dromaeo_css | took 1118363ms 05:15:44 INFO - TEST-START | kraken 05:15:44 INFO - operating with platform_type : mac_ 05:15:44 INFO - Initialising browser for kraken test... 05:15:44 INFO - TEST-INFO | started process 2303 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpz8s9RH/profile http://localhost:49370/getInfo.html) 05:15:46 INFO - PROCESS | 2303 | __metrics Screen width/height:1600/1200 05:15:46 INFO - PROCESS | 2303 | colorDepth:24 05:15:46 INFO - PROCESS | 2303 | Browser inner width/height: 1024/689 05:15:46 INFO - PROCESS | 2303 | __metrics 05:15:46 INFO - PROCESS | 2303 | 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" 05:15:46 INFO - PROCESS | 2303 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:15:47 INFO - TEST-INFO | 2303: exit 0 05:15:47 INFO - Browser initialized. 05:15:47 INFO - Running cycle 1/1 for kraken test... 05:15:47 INFO - TEST-INFO | started process 2305 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpz8s9RH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:59 INFO - PROCESS | 2305 | RSS: Main: 193929216 05:15:59 INFO - PROCESS | 2305 | 05:16:02 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection) 05:16:03 INFO - PROCESS | 2305 | RSS: Main: 244826112 05:16:03 INFO - PROCESS | 2305 | 05:16:05 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-dft) 05:16:06 INFO - PROCESS | 2305 | RSS: Main: 258637824 05:16:06 INFO - PROCESS | 2305 | 05:16:09 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-fft) 05:16:10 INFO - PROCESS | 2305 | RSS: Main: 356962304 05:16:10 INFO - PROCESS | 2305 | 05:16:12 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator) 05:16:12 INFO - PROCESS | 2305 | RSS: Main: 257495040 05:16:12 INFO - PROCESS | 2305 | 05:16:14 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:16:15 INFO - PROCESS | 2305 | RSS: Main: 259174400 05:16:15 INFO - PROCESS | 2305 | 05:16:18 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom) 05:16:19 INFO - PROCESS | 2305 | RSS: Main: 299950080 05:16:19 INFO - PROCESS | 2305 | 05:16:22 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate) 05:16:22 INFO - PROCESS | 2305 | RSS: Main: 302272512 05:16:22 INFO - PROCESS | 2305 | 05:16:25 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial) 05:16:26 INFO - PROCESS | 2305 | RSS: Main: 297496576 05:16:26 INFO - PROCESS | 2305 | 05:16:28 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:16:28 INFO - PROCESS | 2305 | RSS: Main: 276078592 05:16:28 INFO - PROCESS | 2305 | 05:16:30 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:16:31 INFO - PROCESS | 2305 | RSS: Main: 279810048 05:16:31 INFO - PROCESS | 2305 | 05:16:33 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:16:34 INFO - PROCESS | 2305 | RSS: Main: 285687808 05:16:34 INFO - PROCESS | 2305 | 05:16:36 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:16:37 INFO - PROCESS | 2305 | RSS: Main: 290066432 05:16:37 INFO - PROCESS | 2305 | 05:16:40 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:16:41 INFO - PROCESS | 2305 | RSS: Main: 291430400 05:16:41 INFO - PROCESS | 2305 | 05:16:42 INFO - PROCESS | 2305 | Cycle 1(1): loaded http://localhost:49370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49370/tests/kraken/driver.html?testName=ai-astar) 05:16:43 INFO - PROCESS | 2305 | RSS: Main: 336396288 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | __start_tp_report 05:16:43 INFO - PROCESS | 2305 | _x_x_mozilla_page_load 05:16:43 INFO - PROCESS | 2305 | _x_x_mozilla_page_load_details 05:16:43 INFO - PROCESS | 2305 | |i|pagename|runs| 05:16:43 INFO - PROCESS | 2305 | |0;ai-astar;106;116;116;115;115;116;114;120;115;116 05:16:43 INFO - PROCESS | 2305 | |1;audio-beat-detection;145;107;111;154;107;108;109;106;130;111 05:16:43 INFO - PROCESS | 2305 | |2;audio-dft;184;187;186;184;185;186;183;186;185;183 05:16:43 INFO - PROCESS | 2305 | |3;audio-fft;85;62;62;92;64;63;60;60;74;61 05:16:43 INFO - PROCESS | 2305 | |4;audio-oscillator;86;69;87;70;69;69;82;68;68;83 05:16:43 INFO - PROCESS | 2305 | |5;imaging-gaussian-blur;87;87;87;101;135;104;87;86;86;86 05:16:43 INFO - PROCESS | 2305 | |6;imaging-darkroom;94;108;150;97;94;93;94;100;94;94 05:16:43 INFO - PROCESS | 2305 | |7;imaging-desaturate;86;84;86;79;84;86;85;83;85;110 05:16:43 INFO - PROCESS | 2305 | |8;json-parse-financial;60;62;64;59;63;63;63;63;65;63 05:16:43 INFO - PROCESS | 2305 | |9;json-stringify-tinderbox;61;60;65;62;62;58;58;59;59;59 05:16:43 INFO - PROCESS | 2305 | |10;stanford-crypto-aes;81;85;85;86;85;84;83;88;88;102 05:16:43 INFO - PROCESS | 2305 | |11;stanford-crypto-ccm;153;137;124;143;145;146;127;136;149;137 05:16:43 INFO - PROCESS | 2305 | |12;stanford-crypto-pbkdf2;165;165;165;175;216;217;177;164;164;161 05:16:43 INFO - PROCESS | 2305 | |13;stanford-crypto-sha256-iterative;57;80;82;64;66;63;63;64;63;65 05:16:43 INFO - PROCESS | 2305 | __end_tp_report 05:16:43 INFO - PROCESS | 2305 | __start_cc_report 05:16:43 INFO - PROCESS | 2305 | _x_x_mozilla_cycle_collect,2082 05:16:43 INFO - PROCESS | 2305 | __end_cc_report 05:16:43 INFO - PROCESS | 2305 | __startTimestamp1495801003178__endTimestamp 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | ------- Summary: start ------- 05:16:43 INFO - PROCESS | 2305 | Number of tests: 14 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#0] ai-astar Cycles:10 Average:114.90 Median:116.00 stddev:3.51 (3.0%) stddev-sans-first:1.69 05:16:43 INFO - PROCESS | 2305 | Values: 106.0 116.0 116.0 115.0 115.0 116.0 114.0 120.0 115.0 116.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:111.00 stddev:17.73 (16.0%) stddev-sans-first:16.07 05:16:43 INFO - PROCESS | 2305 | Values: 145.0 107.0 111.0 154.0 107.0 108.0 109.0 106.0 130.0 111.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:1.37 (0.7%) stddev-sans-first:1.41 05:16:43 INFO - PROCESS | 2305 | Values: 184.0 187.0 186.0 184.0 185.0 186.0 183.0 186.0 185.0 183.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#3] audio-fft Cycles:10 Average:68.30 Median:63.50 stddev:11.50 (18.1%) stddev-sans-first:10.49 05:16:43 INFO - PROCESS | 2305 | Values: 85.0 62.0 62.0 92.0 64.0 63.0 60.0 60.0 74.0 61.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.00 stddev:8.23 (10.8%) stddev-sans-first:7.72 05:16:43 INFO - PROCESS | 2305 | Values: 86.0 69.0 87.0 70.0 69.0 69.0 82.0 68.0 68.0 83.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:86.50 stddev:15.69 (18.1%) stddev-sans-first:16.39 05:16:43 INFO - PROCESS | 2305 | Values: 87.0 87.0 87.0 101.0 135.0 104.0 87.0 86.0 86.0 86.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:94.00 stddev:17.54 (18.7%) stddev-sans-first:18.38 05:16:43 INFO - PROCESS | 2305 | Values: 94.0 108.0 150.0 97.0 94.0 93.0 94.0 100.0 94.0 94.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:85.00 stddev:8.42 (9.9%) stddev-sans-first:8.92 05:16:43 INFO - PROCESS | 2305 | Values: 86.0 84.0 86.0 79.0 84.0 86.0 85.0 83.0 85.0 110.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.78 (2.8%) stddev-sans-first:1.64 05:16:43 INFO - PROCESS | 2305 | Values: 60.0 62.0 64.0 59.0 63.0 63.0 63.0 63.0 65.0 63.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:2.21 (3.7%) stddev-sans-first:2.33 05:16:43 INFO - PROCESS | 2305 | Values: 61.0 60.0 65.0 62.0 62.0 58.0 58.0 59.0 59.0 59.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:5.77 (6.8%) stddev-sans-first:5.74 05:16:43 INFO - PROCESS | 2305 | Values: 81.0 85.0 85.0 86.0 85.0 84.0 83.0 88.0 88.0 102.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:144.00 stddev:9.30 (6.5%) stddev-sans-first:8.53 05:16:43 INFO - PROCESS | 2305 | Values: 153.0 137.0 124.0 143.0 145.0 146.0 127.0 136.0 149.0 137.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:170.00 stddev:21.48 (12.6%) stddev-sans-first:22.34 05:16:43 INFO - PROCESS | 2305 | Values: 165.0 165.0 165.0 175.0 216.0 217.0 177.0 164.0 164.0 161.0 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - PROCESS | 2305 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:64.50 stddev:7.92 (12.3%) stddev-sans-first:7.58 05:16:43 INFO - PROCESS | 2305 | Values: 57.0 80.0 82.0 64.0 66.0 63.0 63.0 64.0 63.0 65.0 05:16:43 INFO - PROCESS | 2305 | -------- Summary: end -------- 05:16:43 INFO - PROCESS | 2305 | 05:16:43 INFO - TEST-INFO | 2305: exit 0 05:16:43 INFO - TEST-OK | kraken | took 59373ms 05:16:43 INFO - SUITE-END | took 1177s 05:16:44 INFO - Completed test suite (00:19:38) 05:16:44 INFO - javascript benchmark 05:16:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7704.986797881938, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3387.441072223083, 3421.794692318461, 3426.3318652862686, 3459.6540345965423, 3483.4078206704858, 5942.67315297659, 5956.910646340298, 5982.34194238634, 5983.311919129296, 6032.69836508175, 5917.408259174081, 5963.4334738199805, 5967.283925928884, 5996.100584912266, 6002.609830361034, 4542.750148741818, 5284.101702710539, 5326.280952071459, 5339.733013349317, 5392.956625547151, 5265.447128051557, 5267.0256002639635, 5285.101532739443, 5341.359036915575, 5444.3194600674915, 5332.546733527654, 5345.866353341159, 5351.973240133794, 5352.357717073956, 5384.596155288349, 5430.3755068167275, 5435.211894275328, 5457.508824205841, 5458.836234912959, 5474.945250547484, 5288.656237344599, 5383.16560933056, 5387.111126664135, 5400.891982160335, 5423.078076726966, 5703.914441283384, 5755.0, 5988.221366381419, 6000.96999515004, 6069.939300607026, 6202.534809889231, 6256.436920677161, 6257.780977665805, 6309.810705678837, 6341.080543321193, 6336.081268216129, 6351.364863513611, 6405.19935008124, 6407.0, 6414.550981431302, 5615.747291371898, 5966.910496342558, 6101.51187904971, 6102.877942441126, 6123.418275263843, 6178.0, 6211.503079753609, 6381.617102973836, 6420.0, 6520.413162815369, 6302.464290535264, 6314.71583778731, 6317.842053948596, 6325.209348831396, 6326.778562750282, 6340.0, 6352.301246862841, 6378.74485020594, 6392.3927226913365, 6394.776182833579, 5120.769565369567, 6182.350853160443, 6252.468540174045, 6332.746690132343, 6368.649724265118, 6231.096491008779, 6266.436020758154, 6269.247690277196, 6275.0, 6288.717007734663, 4872.171730805771, 4881.804727810848, 4891.559759621651, 4898.314236006961, 4919.1391506486225, 4378.846740364072, 4413.867583972486, 4422.646188304992, 4430.113977204572, 4446.26636604963, 5568.693721845338, 5626.85932851682, 5680.147977803363, 5735.598508104476, 5789.131630255411, 6381.744730210832, 6385.45723613498, 6389.329120442287, 6393.0, 6393.904091438632, 5814.360420353758, 5819.15622234782, 5831.329397119301, 5834.387389324144, 5852.473277405054, 5291.867703307371, 5317.042932272151, 5410.242566040757, 5425.891482170335, 5532.806351777668, 6321.399467050623, 6378.936210637834, 6381.904271436025, 6384.968075159594, 6484.773032943825, 6380.553361264667, 6397.296297407374, 6399.200099987502, 6402.167718196604, 6417.454516366161, 6602.9009564855605, 6605.834854128685, 6606.8017959461295, 6618.90071648916, 6618.966905165444, 6155.35368786271, 6353.36466353361, 6356.77751278703, 6357.046443033582, 6360.077788720702, 6263.3736626337, 6268.341824108403, 6269.467095296951, 6270.874582508233, 6292.119103325446, 6129.695011270001, 6289.08808222796, 6297.4017468340435, 6335.746570137143, 6360.650164241011, 6297.496200304056, 6310.495160387066, 6310.652914089769, 6324.588901721373, 6330.113784070142, 5930.140129681258, 5964.105384192232, 6222.688865556795, 6226.221722284715, 6241.0, 6195.969020155051, 6237.968810156101, 6285.151504546828, 6287.559870808999, 6315.368463153648, 6146.801373732079, 6159.476444502267, 6173.876522469615, 6184.628922264678, 6188.226471691039], "value": 5825.135092064217, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.5870418030534, 823.246729242743, 885.274075258288, 892.1257167975388, 900.9594568244424, 41184.0, 42114.36828447576, 43757.12485750283, 43765.99999999996, 43815.56184438155, 42908.78545607276, 43083.138337233235, 43088.99999999992, 43159.921001974886, 43532.782336088356, 7590.924090759105, 7778.766637000871, 9507.714768556954, 9666.565004574775, 9686.225101991859, 11360.545578176863, 11656.358900260482, 11762.17664763467, 11773.0, 11798.351090690008, 11556.47995840185, 11630.651080467556, 11639.35983520906, 11640.010599099087, 11688.240264382788, 10651.84022239667, 10725.498430219775, 11528.65414037576, 11556.959873611331, 11584.247023943417, 11379.431028448627, 11436.542538298458, 11439.199256051996, 11450.770984580262, 11459.484323205392, 40778.0, 40988.0, 41116.58883411157, 41125.0, 41127.58872411268, 3922.411638254256, 3934.7245692801516, 3992.121733218687, 4002.6597739192202, 4037.1118353962083, 27495.725042749516, 28120.578191327146, 29538.0, 30046.0, 30258.092257232318, 2496.6504689343506, 3056.2664960409343, 3132.0447263584597, 3277.6394596594355, 3324.1024923270825, 347697.9999999975, 348469.0, 349209.0, 349336.99999999744, 349919.2504037489, 54214.728926355114, 54852.0, 54882.17676734852, 55057.449425505234, 55141.72429137828, 56717.582060447996, 56774.0, 56816.0, 56846.43153568411, 56889.71555142198, 14990.62523436901, 15267.389304427921, 15280.083195008336, 15301.6174595636, 15321.6169595761, 12979.41592628334, 12990.350482476028, 12996.870031299755, 13000.934995324962, 13014.869851301366, 12491.687707807196, 13000.699930006924, 13001.544945926848, 13019.088663793633, 13125.212487250605, 5609.607327487037, 5620.747066382022, 5638.097904335369, 5650.519705825051, 5700.144978253295, 855.2986551028097, 931.9860202096974, 932.7361425268799, 959.856021596766, 980.0885176785515, 27392.863035684695, 28301.292467688472, 28469.430611387656, 28923.132306030853, 29090.0, 1050.9474526273655, 1055.2560444886337, 1076.9730756731144, 1094.9452527373758, 1103.9889601104046, 13144.27706476153, 14503.202323872289, 14706.632334191518, 14741.852581474262, 14909.850901491063, 412825.0, 413274.0, 413779.0, 414171.0, 414244.0, 57286.56783580444, 57332.71333643389, 57385.42614573884, 57453.13820292699, 57565.0, 1407.936642851074, 1450.4633285684365, 1453.1499073042178, 1453.6874571967078, 1456.5775924981638, 19540.8045919539, 26590.7340926596, 28461.430771384043, 28709.56935645925, 29020.0, 46692.76653616846, 46793.76603116962, 46909.0, 46965.53034469612, 47013.0, 406399.0, 474461.0, 477067.0, 477173.0, 477195.0, 24470.510589787747, 25046.62430063514, 30068.947586834354, 36374.0, 39479.01302467461, 47314.763426182646, 49055.75472122616, 49281.26078108903, 49790.004199916526, 49829.00341993212, 32545.349093017532, 32600.347993039533, 33113.0, 33433.33133337271, 33708.49437258394, 32536.83731581327, 32587.02238932836, 32709.672903270664, 33279.334413312085, 33600.327993440485, 40311.99220019406, 40375.39436908507, 40559.18881622292, 42056.57943420527, 43229.783851080545, 39978.60021399749, 40178.0, 41482.58517414787, 41829.58170418379, 41956.79021604872, 31511.0, 32180.517292240165, 32321.191970200933, 32650.512115913065, 32729.672703272663], "value": 19717.24309037254, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.82662495377275, 427.24377851203366, 430.09104711364836, 432.33420532380137, 441.9712718673286, 20819.687704684424, 21452.463688407777, 22104.447388815286, 22775.0, 23018.19436319729, 22361.664575031347, 22497.662535061947, 22847.885760571175, 23176.957036933338, 23510.17714379997, 6236.81500514903, 6539.215294164707, 6726.6972986215615, 6963.582185068887, 6973.058637083979, 6799.796006119825, 6802.08171896795, 6843.965780171092, 6864.622445765481, 6959.0, 6564.1138446309615, 6727.798166055026, 6765.424938880201, 6784.626845523519, 6857.314268573127, 6507.219133703986, 6678.36555527229, 6683.799486015428, 6779.966100169492, 6781.186257649089, 6637.834054148637, 6640.867182656368, 6644.501662375293, 6646.833829154261, 6654.168228971379, 23591.174308899277, 24470.877645611836, 26707.0, 26830.195094147206, 27142.0, 2977.8607735248024, 2997.1308320587004, 3011.864466099009, 3018.26052617108, 3031.8332491712945, 15988.280527376295, 16599.66800663992, 16663.16684165787, 16877.493675189762, 17009.914950425293, 2070.188297988253, 2095.2399884702086, 2140.2723074154706, 2163.772803855604, 2210.2264207527396, 126894.0, 130019.34990325083, 132413.0, 132597.0, 133360.6663933358, 19499.44004479638, 21437.67843482349, 22285.77714222837, 22452.0, 22952.0, 33449.66550334514, 35693.0, 35779.82110089485, 36507.26985460276, 36683.81658091746, 8663.740087797376, 10303.278770485995, 10441.425721585234, 10627.734306642396, 10637.840432393521, 8677.284525060912, 8747.0, 8748.8687669685, 8905.51019693923, 8959.790428292228, 9521.666741664008, 9577.156501840558, 9766.316357854881, 9791.363561368631, 9806.12658000086, 4157.334826427757, 4267.573242675708, 4292.59220374064, 4295.9904422460695, 4304.20372231132, 956.53129966316, 966.8259713251541, 984.5471083301618, 992.4541502173776, 1025.3847691385108, 22751.317460476213, 23035.654465183037, 23040.53918921612, 23051.308460746204, 23186.304410867702, 930.874331965176, 990.9702708918744, 993.8509223616516, 1014.9441780702132, 1020.2093377632245, 10574.15406767472, 11453.770924581462, 11887.762244755057, 12201.14591978553, 12218.633440996784, 742300.0, 744545.0, 744713.0, 761341.0, 774892.0, 74908.0, 83772.0, 99186.51220231703, 125346.37326813307, 129985.70014299736, 1268.866768989243, 1309.6594885329691, 1331.4940322677128, 1342.9664258393618, 1345.5895951734815, 20511.12844201783, 20879.8956005219, 21124.943752812604, 21128.260510882046, 21444.46388840229, 49956.750216248685, 55353.44646553483, 55464.44535554754, 55467.445325547844, 56496.43503564912, 75160.24839751533, 75768.0, 77298.4540309202, 77591.0, 78390.0, 11609.535618575163, 11772.764544709229, 12015.819762703391, 12253.264804111783, 12297.692557686129, 13198.14212076212, 13209.933950330571, 13294.800577991531, 13369.465221391036, 13636.181829090287, 9189.791321137815, 11271.830922536004, 11346.773064538498, 11370.431478426211, 11659.18385712992, 10597.682069537927, 10621.468926553796, 10706.304090234109, 10824.4046577439, 11043.723906902393, 11599.420028998686, 11665.533378664759, 11702.70743231426, 11801.468933897824, 11814.054875610102, 11804.0, 11845.348505832262, 12086.939565302117, 12178.57374991871, 12192.817107743569, 11293.943530282624, 11376.715582110513, 11514.82727759101, 11543.942280288546, 11692.47383867745], "value": 11608.366313050883, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.6718321969167, 1578.4948816378762, 1580.6169601598601, 1609.4527860527426, 1614.8869579129466, 10604.0, 10739.516721747521, 11309.151813613971, 11313.264637798547, 11313.377764222965, 10016.799664006716, 10866.67399978, 11218.0, 11288.661340159808, 11297.265677730942, 2325.197806756666, 2369.917052903149, 2372.644103384489, 2378.0250097460043, 2381.273711517986, 2264.6603009548617, 2326.406766274606, 2353.9293821185306, 2360.7639236076425, 2363.9763602364014, 2279.1453205048106, 2344.882755862209, 2345.870977096259, 2355.9411014724596, 2362.9645555316683, 2288.1533832481923, 2302.861828290308, 2329.440934175794, 2338.8479748816358, 2343.308723926446, 2349.119080344871, 2352.2002519143575, 2358.4221865642858, 2362.350353652759, 2367.846090004144, 11105.888941110567, 11201.663950081429, 11209.495572699248, 11220.551177952872, 11220.607278745205, 5376.650517716336, 5430.619856610039, 5608.635438696471, 5622.522085622686, 5626.634268772516, 3294.8846790362463, 3422.3326451342, 3460.186856088817, 3501.8074005929666, 3523.7004854587135, 1102.2504696806168, 1130.2257953302014, 1164.889335513125, 1171.3264872698148, 1197.8442802435627, 15456.381744730197, 15476.0, 15546.533603991898, 15575.76636350467, 15611.921940390339, 4198.181354635817, 4430.0, 4483.596476317082, 4494.865154045384, 4530.841420550265, 11574.826377604342, 11600.94199528997, 11608.0, 11619.01238394729, 11639.777823328597, 5533.833984980457, 5547.472990065938, 5548.9722551386985, 5555.77776888928, 5562.221289019541, 4974.129527332702, 4977.0, 4981.9252711209365, 4995.150824359867, 5076.213186956028, 3146.363890776829, 3580.4271316589743, 3643.763155394944, 3660.762050466711, 3686.8156592170285, 915.5285028210477, 927.0682963621517, 931.4085555672197, 932.6455946740331, 939.0093451408829, 961.9086186812242, 965.8937516873139, 976.9316147869581, 986.9259805514472, 1009.4498498318434, 6609.966950165219, 6713.328667133248, 6808.8978665320055, 6903.240643529208, 6926.722931082701, 878.5124256037833, 882.298572634757, 971.155095067296, 997.4514017290461, 1037.9429131397847, 3678.429843374281, 4114.362273847559, 4159.771212583277, 4331.415258940003, 4491.865244042684, 13183.604491865259, 13897.305134743223, 13922.582322530137, 13955.441782328593, 13958.162510249214, 13513.256770877499, 13561.796573051412, 13707.657308567366, 13869.930650346683, 13890.236037018061, 10186.388816671024, 10235.948820255851, 10310.762708475031, 10331.966803319758, 10346.172306215485, 5852.770918107245, 6237.064440333987, 6567.0, 6681.1314529110905, 6739.898901516579, 12178.817317740419, 12184.20802647825, 12272.509099635914, 12281.01751859867, 12291.69270768238, 12523.74952500963, 12539.623811285675, 12688.17526860751, 12726.427310771223, 12777.808332874829, 8463.026751923362, 8696.956515217384, 8697.95651021741, 8711.477311361085, 8715.95408550978, 10007.349522281029, 10297.794044119226, 10335.586576536854, 10342.638007669695, 10360.222983276133, 8656.740297791077, 8788.033316335326, 8881.511516866376, 8919.643214271357, 8994.505302208441, 8645.092265312052, 8700.477971321741, 8705.26005289532, 8791.604377802887, 8798.780030499289, 7880.0, 8081.9191808083515, 8088.150744172012, 8091.716789912325, 8104.149064348158, 5830.5044071254415, 5903.2325797646035, 5947.672877991752, 5983.790567330123, 6128.0, 5955.67243801595, 6064.969675151596, 6112.052631842072, 6173.783917562864, 6177.351378105234], "value": 5074.183149199544, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.3798989757821, 368.92068205335846, 384.66918450132897, 385.7454080306998, 390.0560643243352, 4061.9535348488635, 4732.763361831903, 4802.879928001802, 5034.521720436562, 5073.391193056834, 4205.915881682365, 4242.787860606965, 4791.771826100862, 4909.33723947267, 4934.420985284711, 3567.1807378237154, 3809.6380843819793, 4125.814338354766, 4278.764667943262, 4296.935545966805, 3633.6366363363586, 3750.6999440044733, 3919.7652739387145, 4070.0085437203634, 4363.869083927471, 3630.455431685255, 4134.635570261807, 4137.338025915854, 4249.024203745258, 4310.4611923509565, 3630.3679832121766, 3719.20037192005, 3755.699544036484, 4249.192653395876, 4300.6989510734265, 3766.397376419787, 3938.921221575581, 4116.9794151029355, 4133.276676581616, 4178.979105104486, 3939.740446503921, 4284.507281662586, 4760.785764640599, 4784.392506084192, 4902.970376220997, 4561.833129263573, 4637.211674015425, 4644.419447569054, 4678.672492925497, 4899.265110233458, 4719.528047195287, 5058.064258112261, 5292.497212764782, 5335.653182543122, 5353.812616558402, 3872.9031774205587, 4040.878773636796, 4066.8373265069363, 4323.891902702426, 4613.192691279013, 4788.880277993008, 5371.382291036542, 5387.73388253764, 5613.298337707786, 5626.324841019104, 4693.404242557547, 4706.070431189926, 4766.737829419346, 4770.976145119253, 4788.976055119702, 4577.496475387705, 4730.95269047312, 4776.450708168605, 4806.94247265601, 4876.3183796234325, 4133.441985331941, 4397.494288156903, 4429.844955426544, 4439.556044395535, 4913.434954980223, 4234.978825105854, 5213.983273261753, 5297.761600727977, 5306.867328316745, 5316.245638939218, 1344.2740919903338, 1386.5008596905107, 1423.5444657709434, 1496.3790027138616, 1505.5784380373514, 1179.7994340962057, 1206.6078524479578, 1277.482619539088, 1335.1455068756004, 1340.5710172744627, 4224.936625950613, 4301.720388174758, 4498.8650340489185, 4610.400647915817, 4634.980304333041, 4491.136178485892, 5064.848054558369, 5390.299261096033, 5434.538064264581, 5436.673799572039, 4161.375793630979, 4478.977605111953, 4685.742284174335, 4694.647901407408, 4721.929171062437, 2197.5385169114666, 2703.513367593852, 2724.6594175728032, 2820.801159507201, 2831.3912508810704, 4266.488021437447, 4870.561649451566, 5044.445111037783, 5512.834614961477, 5597.776088956396, 5242.659227150223, 5505.366882808528, 5525.778968841201, 5526.336839579276, 5526.530244929146, 5581.609287349927, 5583.553315734732, 5595.922242766462, 5599.496045355938, 5626.634268772516, 4850.0, 5019.954945280117, 5127.41034781005, 5298.496642818926, 5419.160030195326, 4215.283401821636, 4449.532799056053, 4505.414296141481, 4991.9500804991485, 5249.687578105473, 5556.666600004012, 5560.663601838864, 5582.3859375467855, 5590.875467322554, 5618.831435056954, 4092.7339722919105, 4201.768902710381, 4605.555638808566, 4710.034442939121, 4733.408323959505, 4953.356063711694, 4962.751862406793, 5116.0, 5184.029081138766, 5499.697516636624, 4628.4445866496235, 4687.835925742583, 5113.105206588906, 5126.974365128151, 5301.045811753767, 4810.254410566367, 4950.851474455772, 5023.8492845214705, 5093.205561856441, 5316.494932981361, 4510.2783554631105, 4989.276554899592, 5176.767045483038, 5211.296474975829, 5236.952609478043, 4894.241392584013, 4972.801087956586, 5242.868928276824, 5314.574834013192, 5331.29475014861, 4825.469198388104, 5160.767765450489, 5199.584033277404, 5219.582433405394, 5222.3472065991755], "value": 4066.2193058900857, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.3591857125016, 746.9290417410348, 782.3154739602847, 841.5077179849787, 860.8020155364268, 15783.36866525339, 16611.750823737653, 16628.334866605335, 16629.83370166298, 16669.749953750674, 12564.81152782709, 15130.0, 15200.0, 15264.084154950711, 15285.388584456608, 959.6976952260051, 970.6602689058843, 977.5942983661807, 981.7496538382702, 1002.6039714312842, 964.4358050540442, 983.650803964593, 985.2265971212599, 986.3145114145672, 988.298308201178, 1043.8068957242897, 1045.8640376751011, 1047.607147319755, 1047.8690163729534, 1048.2190767877942, 1039.552992213348, 1040.3081950502906, 1041.208681402136, 1043.0143514378915, 1043.1498328861992, 772.6136931534232, 773.2190487607501, 773.2576726342718, 774.3069952392577, 774.6939958716332, 16736.41422550205, 16752.329906803712, 16796.83203167965, 17278.740818887727, 17508.47474575752, 14481.348339324755, 14531.78202326966, 14535.927320363437, 14568.344424500923, 14668.633284167874, 11857.584984525587, 11990.801576872575, 11991.700207494796, 12038.518459261619, 12482.188657737308, 3681.852725890961, 3786.8674596388996, 3802.1445174835717, 3802.9619703802887, 3947.3684210526176, 114406.0, 116434.0, 119428.80571194264, 121309.78690213073, 121760.56478870462, 10345.0, 10374.948125259325, 10567.313124646873, 10750.247482676294, 10794.78410431787, 23020.4244893879, 23049.308520744406, 23051.7694823053, 23066.769332306798, 23077.0, 6501.512386570931, 7829.3345065670055, 8080.717174898968, 8090.676372945016, 8167.550784706779, 4378.1900348435, 4518.0, 4554.997900461893, 4577.153226653079, 4601.792919318638, 3314.784539004957, 3332.3835090508333, 3358.714509266691, 3383.9661603384143, 3386.3227354529195, 596.0850095103995, 596.6241268001131, 611.7247238742584, 619.3713380918372, 619.7613918641265, 560.7812952948353, 712.8279157748042, 720.2221600671262, 725.7543371278086, 730.2332550821616, 10293.794124117476, 10427.635032773816, 10499.737506562244, 10507.684769456928, 10691.518881650345, 700.6111608057577, 713.4934196720386, 719.7229066809316, 719.9856002879914, 739.2644318902619, 4828.565429111397, 5091.541761241506, 5097.668651537638, 5107.208382700687, 5115.462876397999, 90915.54542227379, 101302.49348753209, 102666.94666106637, 105793.0, 107406.0, 66166.33833661601, 77148.0, 77634.2236577627, 82869.58565207256, 86926.56536717403, 56786.14820777609, 68427.0, 69347.30652693409, 74750.6262468695, 75679.0, 8847.42491738035, 11092.11263098934, 11192.664220073411, 11351.14866385041, 11378.260413073123, 39182.0, 47589.0, 47733.52266477291, 49858.75070624769, 49883.50116498789, 61731.765364693354, 62439.8780060986, 63832.36167638264, 64559.0, 64861.70276594536, 10809.729756756144, 10818.945905270422, 10997.580532282886, 12798.296093714935, 13576.117552359065, 12085.81871271914, 12380.4428800706, 12964.675883102998, 13188.208707477583, 13291.401886915306, 11566.421678915853, 11825.763484730429, 12080.75838483243, 12129.272243665408, 12336.198147120409, 11596.420178990847, 11884.286942783461, 12239.632811015685, 12304.384780761106, 12430.751384972069, 12970.0, 13471.663208419868, 13938.93030534841, 14377.496787612383, 14378.0, 8907.752914592093, 9201.447913125234, 9649.758756031155, 9852.359596626484, 10302.969703029637, 9880.802383952137, 10117.443540605338, 10378.38916465018, 11395.542522579226, 11604.593839215924], "value": 7605.116488795997, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1438.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 115.0, 115.0, 116.0, 114.0, 120.0, 115.0, 116.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 107.0, 111.0, 154.0, 107.0, 108.0, 109.0, 106.0, 130.0, 111.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 186.0, 184.0, 185.0, 186.0, 183.0, 186.0, 185.0, 183.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 62.0, 92.0, 64.0, 63.0, 60.0, 60.0, 74.0, 61.0], "value": 68.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 87.0, 70.0, 69.0, 69.0, 82.0, 68.0, 68.0, 83.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 101.0, 135.0, 104.0, 87.0, 86.0, 86.0, 86.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 108.0, 150.0, 97.0, 94.0, 93.0, 94.0, 100.0, 94.0, 94.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 79.0, 84.0, 86.0, 85.0, 83.0, 85.0, 110.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 64.0, 59.0, 63.0, 63.0, 63.0, 63.0, 65.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 65.0, 62.0, 62.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 85.0, 86.0, 85.0, 84.0, 83.0, 88.0, 88.0, 102.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 137.0, 124.0, 143.0, 145.0, 146.0, 127.0, 136.0, 149.0, 137.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 165.0, 175.0, 216.0, 217.0, 177.0, 164.0, 164.0, 161.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 80.0, 82.0, 64.0, 66.0, 63.0, 63.0, 64.0, 63.0, 65.0], "value": 66.7, "unit": "score"}]}]} 05:16:44 INFO - Return code: 0 05:16:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:16:44 INFO - # TBPL SUCCESS # 05:16:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:44 INFO - 2017-05-26 05:16:44.501 screenresolution[2309:282915] starting screenresolution argv=screenresolution get 05:16:44 INFO - 2017-05-26 05:16:44.504 screenresolution[2309:282915] Display 0: 1600x1200x32@60 05:16:44 INFO - 2017-05-26 05:16:44.509 screenresolution[2310:282918] starting screenresolution argv=screenresolution list 05:16:44 INFO - Available Modes on Display 0 05:16:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:44 INFO - Intel Iris: 05:16:44 INFO - Chipset Model: Intel Iris 05:16:44 INFO - Type: GPU 05:16:44 INFO - Bus: Built-In 05:16:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:44 INFO - Vendor: Intel (0x8086) 05:16:44 INFO - Device ID: 0x0a2e 05:16:44 INFO - Revision ID: 0x0009 05:16:44 INFO - Displays: 05:16:44 INFO - Display: 05:16:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:44 INFO - Display Serial Number: 1600x1200 60 05:16:44 INFO - Main Display: Yes 05:16:44 INFO - Mirror: Off 05:16:44 INFO - Online: Yes 05:16:44 INFO - Rotation: Supported 05:16:44 INFO - Return code: 0 05:16:44 INFO - Running post-action listener: _resource_record_post_action 05:16:44 INFO - [mozharness: 2017-05-26 12:16:44.744661Z] Finished run-tests step (success) 05:16:44 INFO - Running post-run listener: _resource_record_post_run 05:16:44 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 544768; Write bytes: 510135808; Read time: 9; Write time: 1536 05:16:44 INFO - TinderboxPrint: CPU usage
53.8% 05:16:44 INFO - TinderboxPrint: I/O read bytes / time
544,768 / 9 05:16:44 INFO - TinderboxPrint: I/O write bytes / time
510,135,808 / 1,536 05:16:44 INFO - TinderboxPrint: CPU idle
2,215.2 (46.2%) 05:16:44 INFO - TinderboxPrint: CPU system
954.9 (19.9%) 05:16:44 INFO - TinderboxPrint: CPU user
1,627.7 (33.9%) 05:16:44 INFO - TinderboxPrint: Swap in / out
209,215,488 / 0 05:16:44 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203969024; Write bytes: 202893312; Read time: 15597; Write time: 404 05:16:44 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 544768; Write bytes: 296441344; Read time: 9; Write time: 1091 05:16:45 INFO - Running post-run listener: _upload_blobber_files 05:16:45 INFO - Blob upload gear active. 05:16:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:16:45 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 05:16:45 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'] 05:16:45 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 05:16:45 INFO - (blobuploader) - INFO - Open directory for files ... 05:16:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:16:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:16:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:46 INFO - (blobuploader) - INFO - Done attempting. 05:16:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:16:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:16:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:47 INFO - (blobuploader) - INFO - Done attempting. 05:16:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:16:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:16:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:48 INFO - (blobuploader) - INFO - Done attempting. 05:16:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:16:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:51 INFO - (blobuploader) - INFO - Done attempting. 05:16:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:16:51 INFO - Return code: 0 05:16:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:16:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:16:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61ad51cbaf5f3fcae19fab4d6b55cbebb55fe5f11d9b7e802c721f21b9bc6a4574cdc4ec9053b758a3cecbc7aacb93bb906b362e8cf9d5cb36bf9ca98ed90549", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc0bf6a0388e1ca80be85cae58a9fba1ef6733c7b832d5ac98e175252ee71f0d8c1d4e83774ecc0d242eb0bc0bbdc7ad3271ff7d529d619f35cee6b11f1bd97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc550f1fb2d203769eb57e04cce1ea54ae4b1d80f75392c8e8140911cddd753fe454ce92b75c6fa96fef15880eac2c84c700c3c1cdf62ea1183b1b8c2576f452", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8423598fac3ba1eb714d78ecfe649c155372205e6f04c0653a27e79cdde5d212c64ed8877e10777dbe234b9571649b3139bf32b4e6aa1cdd73ebdf6c92505f90"} 05:16:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:16:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:16:51 INFO - Contents: 05:16:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/61ad51cbaf5f3fcae19fab4d6b55cbebb55fe5f11d9b7e802c721f21b9bc6a4574cdc4ec9053b758a3cecbc7aacb93bb906b362e8cf9d5cb36bf9ca98ed90549", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc0bf6a0388e1ca80be85cae58a9fba1ef6733c7b832d5ac98e175252ee71f0d8c1d4e83774ecc0d242eb0bc0bbdc7ad3271ff7d529d619f35cee6b11f1bd97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc550f1fb2d203769eb57e04cce1ea54ae4b1d80f75392c8e8140911cddd753fe454ce92b75c6fa96fef15880eac2c84c700c3c1cdf62ea1183b1b8c2576f452", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8423598fac3ba1eb714d78ecfe649c155372205e6f04c0653a27e79cdde5d212c64ed8877e10777dbe234b9571649b3139bf32b4e6aa1cdd73ebdf6c92505f90"} 05:16:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:16:51 INFO - Copying logs to upload dir... 05:16:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:16:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.834240 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-26 05:16:51.561131) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 05:16:51.567163) ========= 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.Q0BgViBQyY/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.EGjnbAURCu/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/61ad51cbaf5f3fcae19fab4d6b55cbebb55fe5f11d9b7e802c721f21b9bc6a4574cdc4ec9053b758a3cecbc7aacb93bb906b362e8cf9d5cb36bf9ca98ed90549", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc0bf6a0388e1ca80be85cae58a9fba1ef6733c7b832d5ac98e175252ee71f0d8c1d4e83774ecc0d242eb0bc0bbdc7ad3271ff7d529d619f35cee6b11f1bd97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc550f1fb2d203769eb57e04cce1ea54ae4b1d80f75392c8e8140911cddd753fe454ce92b75c6fa96fef15880eac2c84c700c3c1cdf62ea1183b1b8c2576f452", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8423598fac3ba1eb714d78ecfe649c155372205e6f04c0653a27e79cdde5d212c64ed8877e10777dbe234b9571649b3139bf32b4e6aa1cdd73ebdf6c92505f90"} build_url:https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/AeZ587X9Sd6fzPPDv_rJfA/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/61ad51cbaf5f3fcae19fab4d6b55cbebb55fe5f11d9b7e802c721f21b9bc6a4574cdc4ec9053b758a3cecbc7aacb93bb906b362e8cf9d5cb36bf9ca98ed90549", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fc0bf6a0388e1ca80be85cae58a9fba1ef6733c7b832d5ac98e175252ee71f0d8c1d4e83774ecc0d242eb0bc0bbdc7ad3271ff7d529d619f35cee6b11f1bd97a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc550f1fb2d203769eb57e04cce1ea54ae4b1d80f75392c8e8140911cddd753fe454ce92b75c6fa96fef15880eac2c84c700c3c1cdf62ea1183b1b8c2576f452", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8423598fac3ba1eb714d78ecfe649c155372205e6f04c0653a27e79cdde5d212c64ed8877e10777dbe234b9571649b3139bf32b4e6aa1cdd73ebdf6c92505f90"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 05:16:51.603430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 05:16:51.603806) ========= 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.Q0BgViBQyY/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.EGjnbAURCu/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.005375 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 05:16:51.652592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 05:16:51.652972) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 05:16:51.653332) ========= ========= Total master_lag: 0.19 =========