builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0276 starttime: 1494868459.39 results: success (0) buildid: 20170515090825 builduid: 36998ef6d21e462cb961f18418cc3a0e revision: b0a01d9a55898e9cdaa3a544c8183da2141199fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.393857) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.394354) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.394649) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.419275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.419666) ========= 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.ypk8HSSfxj/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.iYZraaYXmg/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-15 10:14:19-- 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% 8.09M=0.001s 2017-05-15 10:14:19 (8.09 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129241 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.571798) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.572139) ========= 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.ypk8HSSfxj/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.iYZraaYXmg/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.021179 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.621016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-15 10:14:19.621388) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b0a01d9a55898e9cdaa3a544c8183da2141199fb --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 b0a01d9a55898e9cdaa3a544c8183da2141199fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ypk8HSSfxj/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.iYZraaYXmg/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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-15 10:14:19,715 truncating revision to first 12 chars 2017-05-15 10:14:19,715 Setting DEBUG logging. 2017-05-15 10:14:19,715 attempt 1/10 2017-05-15 10:14:19,715 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b0a01d9a5589?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 10:14:20,149 unpacking tar archive at: mozilla-beta-b0a01d9a5589/testing/mozharness/ program finished with exit code 0 elapsedTime=0.711934 ========= master_lag: 2.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-15 10:14:22.747560) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:22.747885) ========= script_repo_revision: b0a01d9a55898e9cdaa3a544c8183da2141199fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:22.748294) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:22.748563) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:22.769044) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 10:14:22.769446) ========= /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.ypk8HSSfxj/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.iYZraaYXmg/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:14:22 INFO - MultiFileLogger online at 20170515 10:14:22 in /builds/slave/test 10:14:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:14:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:14:22 INFO - {'append_to_log': False, 10:14:22 INFO - 'base_work_dir': '/builds/slave/test', 10:14:22 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:14:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:14:22 INFO - 'branch': 'Mozilla-Beta', 10:14:22 INFO - 'buildbot_json_path': 'buildprops.json', 10:14:22 INFO - 'config_files': ('talos/mac_config.py',), 10:14:22 INFO - 'default_actions': ('clobber', 10:14:22 INFO - 'read-buildbot-config', 10:14:22 INFO - 'download-and-extract', 10:14:22 INFO - 'populate-webroot', 10:14:22 INFO - 'create-virtualenv', 10:14:22 INFO - 'install', 10:14:22 INFO - 'run-tests'), 10:14:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:14:22 INFO - 'download_minidump_stackwalk': True, 10:14:22 INFO - 'download_symbols': 'ondemand', 10:14:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:14:22 INFO - 'tooltool.py': '/tools/tooltool.py', 10:14:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:14:22 INFO - '/tools/misc-python/virtualenv.py')}, 10:14:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:14:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:14:22 INFO - 'gecko_profile': False, 10:14:22 INFO - 'gecko_profile_interval': 0, 10:14:22 INFO - 'installer_path': 'installer.exe', 10:14:22 INFO - 'log_level': 'info', 10:14:22 INFO - 'log_name': 'talos', 10:14:22 INFO - 'log_to_console': True, 10:14:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:14:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:14:22 INFO - 'opt_config_files': (), 10:14:22 INFO - 'pip_index': False, 10:14:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:22 INFO - 'cmd': ('bash', 10:14:22 INFO - '-c', 10:14:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:22 INFO - 'enabled': True, 10:14:22 INFO - 'halt_on_failure': False, 10:14:22 INFO - 'name': 'check_screen_resolution'},), 10:14:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:22 INFO - 'cmd': ('bash', 10:14:22 INFO - '-c', 10:14:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:22 INFO - 'enabled': True, 10:14:22 INFO - 'halt_on_failure': False, 10:14:22 INFO - 'name': 'check_screen_resolution'},), 10:14:22 INFO - 'run_cmd_checks_enabled': True, 10:14:22 INFO - 'suite': 'dromaeojs', 10:14:22 INFO - 'system_bits': '32', 10:14:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:14:22 INFO - 'title': 't-yosemite-r7-0276', 10:14:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:14:22 INFO - 'use_talos_json': True, 10:14:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:14:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:14:22 INFO - 'work_dir': 'build'} 10:14:22 INFO - [mozharness: 2017-05-15 17:14:22.983404Z] Running clobber step. 10:14:22 INFO - Running pre-action listener: _resource_record_pre_action 10:14:22 INFO - Running main action method: clobber 10:14:22 INFO - rmtree: /builds/slave/test/build 10:14:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:14:25 INFO - Running post-action listener: _resource_record_post_action 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.000758Z] Finished clobber step (success) 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.000896Z] Running read-buildbot-config step. 10:14:25 INFO - Running pre-action listener: _resource_record_pre_action 10:14:25 INFO - Running main action method: read_buildbot_config 10:14:25 INFO - Using buildbot properties: 10:14:25 INFO - { 10:14:25 INFO - "project": "", 10:14:25 INFO - "product": "firefox", 10:14:25 INFO - "script_repo_revision": "production", 10:14:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 10:14:25 INFO - "repository": "", 10:14:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 10:14:25 INFO - "buildid": "20170515090825", 10:14:25 INFO - "pgo_build": "False", 10:14:25 INFO - "basedir": "/builds/slave/test", 10:14:25 INFO - "buildnumber": 8, 10:14:25 INFO - "slavename": "t-yosemite-r7-0276", 10:14:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 10:14:25 INFO - "platform": "yosemite_r7", 10:14:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:14:25 INFO - "branch": "mozilla-beta", 10:14:25 INFO - "repo_path": "releases/mozilla-beta", 10:14:25 INFO - "revision": "b0a01d9a55898e9cdaa3a544c8183da2141199fb", 10:14:25 INFO - "stage_platform": "macosx64", 10:14:25 INFO - "builduid": "36998ef6d21e462cb961f18418cc3a0e", 10:14:25 INFO - "slavebuilddir": "test" 10:14:25 INFO - } 10:14:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:14:25 INFO - Running post-action listener: _resource_record_post_action 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.004158Z] Finished read-buildbot-config step (success) 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.004267Z] Running download-and-extract step. 10:14:25 INFO - Running pre-action listener: _resource_record_pre_action 10:14:25 INFO - Running main action method: download_and_extract 10:14:25 INFO - mkdir: /builds/slave/test/build/tests 10:14: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')]} 10:14:25 INFO - trying https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:14:25 INFO - Downloading https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:14:26 INFO - Downloaded 1287 bytes. 10:14:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:14:26 INFO - Using the following test package requirements: 10:14:26 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:14:26 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:14:26 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:14:26 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:14:26 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:14:26 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:14:26 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:14:26 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:14:26 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:14:26 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:14:26 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:14:26 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:14:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:14:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:14:26 INFO - Fetch https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:14:27 INFO - Expected file size: 51224155 10:14:27 INFO - Obtained file size: 51224155 10:14:44 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:14:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:14:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:14:44 INFO - Fetch https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:14:46 INFO - Expected file size: 51224155 10:14:46 INFO - Obtained file size: 51224155 10:15:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:15:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:15:01 INFO - Fetch https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:15:02 INFO - Expected file size: 14404449 10:15:02 INFO - Obtained file size: 14404449 10:15:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:02 INFO - trying https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:15:02 INFO - Downloading https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:15:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:15:04 INFO - Downloaded 59539518 bytes. 10:15:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:15:04 INFO - mkdir: /builds/slave/test/properties 10:15:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:15:04 INFO - Writing to file /builds/slave/test/properties/build_url 10:15:04 INFO - Contents: 10:15:04 INFO - build_url:https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:15:05 INFO - Running post-action listener: _resource_record_post_action 10:15:05 INFO - Running post-action listener: set_extra_try_arguments 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.217303Z] Finished download-and-extract step (success) 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.217435Z] Running populate-webroot step. 10:15:05 INFO - Running pre-action listener: _resource_record_pre_action 10:15:05 INFO - Running main action method: populate_webroot 10:15:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:15:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:15:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:15:05 INFO - '--tppagecycles', 10:15:05 INFO - '7'], 10:15:05 INFO - 'tests': ['tsvgm']}}, 10:15:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:15:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:15:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:15:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:15:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['damp', 'tps']}, 10:15:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'tests': ['damp', 'tps']}, 10:15:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['dromaeo_dom']}, 10:15:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:15:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['basic_compositor_video']}, 10:15:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:15:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other-e10s': {'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tsvgx', 10:15:05 INFO - 'tsvgr_opacity', 10:15:05 INFO - 'tart', 10:15:05 INFO - 'tscrollx', 10:15:05 INFO - 'cart', 10:15:05 INFO - 'tsvg_static']}, 10:15:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:15:05 INFO - 'tsvgr_opacity', 10:15:05 INFO - 'tart', 10:15:05 INFO - 'tscrollx', 10:15:05 INFO - 'cart', 10:15:05 INFO - 'tsvg_static']}, 10:15:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tp5o']}, 10:15:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:15:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s', 10:15:05 INFO - '--xperf_path', 10:15:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:05 INFO - 'tests': ['tp5n']}, 10:15:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--xperf_path', 10:15:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:05 INFO - 'tests': ['tp5n']}}, 10:15:05 INFO - 'talos.zip': {'path': '', 10:15:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:15:05 INFO - Running post-action listener: _resource_record_post_action 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.235788Z] Finished populate-webroot step (success) 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.235917Z] Running create-virtualenv step. 10:15:05 INFO - Running pre-action listener: _resource_record_pre_action 10:15:05 INFO - Running main action method: create_virtualenv 10:15:05 INFO - Creating virtualenv /builds/slave/test/build/venv 10:15:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:15:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:15:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:15:05 INFO - Using real prefix '/tools/python27' 10:15:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:15:06 INFO - Installing distribute.............................................................................................................................................................................................done. 10:15:08 INFO - Installing pip.................done. 10:15:08 INFO - Return code: 0 10:15:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:15:09 INFO - Reading from file tmpfile_stdout 10:15:09 INFO - Output received: 10:15:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:15:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:09 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:09 INFO - 'HOME': '/Users/cltbld', 10:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:09 INFO - 'LOGNAME': 'cltbld', 10:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:09 INFO - 'MOZ_NO_REMOTE': '1', 10:15:09 INFO - 'NO_EM_RESTART': '1', 10:15:09 INFO - 'PAGER': '/bin/cat', 10:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:09 INFO - 'PWD': '/builds/slave/test', 10:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:09 INFO - 'SHELL': '/bin/bash', 10:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:09 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:09 INFO - 'USER': 'cltbld', 10:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:09 INFO - 'XPC_FLAGS': '0x0', 10:15:09 INFO - 'XPC_SERVICE_NAME': '0', 10:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:15:09 INFO - Cleaning up... 10:15:09 INFO - Return code: 0 10:15:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:09 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:09 INFO - 'HOME': '/Users/cltbld', 10:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:09 INFO - 'LOGNAME': 'cltbld', 10:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:09 INFO - 'MOZ_NO_REMOTE': '1', 10:15:09 INFO - 'NO_EM_RESTART': '1', 10:15:09 INFO - 'PAGER': '/bin/cat', 10:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:09 INFO - 'PWD': '/builds/slave/test', 10:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:09 INFO - 'SHELL': '/bin/bash', 10:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:09 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:09 INFO - 'USER': 'cltbld', 10:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:09 INFO - 'XPC_FLAGS': '0x0', 10:15:09 INFO - 'XPC_SERVICE_NAME': '0', 10:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:09 INFO - Downloading/unpacking psutil>=3.1.1 10:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:15:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:11 INFO - Installing collected packages: psutil 10:15:11 INFO - Running setup.py install for psutil 10:15:11 INFO - building 'psutil._psutil_osx' extension 10:15:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:15:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:15:12 INFO - building 'psutil._psutil_posix' extension 10:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:15:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:15:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:15:12 INFO - ^ 10:15:12 INFO - 1 warning generated. 10:15:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:12 INFO - Successfully installed psutil 10:15:12 INFO - Cleaning up... 10:15:12 INFO - Return code: 0 10:15:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:15:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:15:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:12 INFO - 'HOME': '/Users/cltbld', 10:15:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:12 INFO - 'LOGNAME': 'cltbld', 10:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:12 INFO - 'MOZ_NO_REMOTE': '1', 10:15:12 INFO - 'NO_EM_RESTART': '1', 10:15:12 INFO - 'PAGER': '/bin/cat', 10:15:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:12 INFO - 'PWD': '/builds/slave/test', 10:15:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:12 INFO - 'SHELL': '/bin/bash', 10:15:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:12 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:12 INFO - 'USER': 'cltbld', 10:15:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:12 INFO - 'XPC_FLAGS': '0x0', 10:15:12 INFO - 'XPC_SERVICE_NAME': '0', 10:15:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:15:14 INFO - Installing collected packages: mozsystemmonitor 10:15:14 INFO - Running setup.py install for mozsystemmonitor 10:15:14 INFO - Successfully installed mozsystemmonitor 10:15:14 INFO - Cleaning up... 10:15:14 INFO - Return code: 0 10:15:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:14 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:15:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:14 INFO - 'HOME': '/Users/cltbld', 10:15:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:14 INFO - 'LOGNAME': 'cltbld', 10:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:14 INFO - 'MOZ_NO_REMOTE': '1', 10:15:14 INFO - 'NO_EM_RESTART': '1', 10:15:14 INFO - 'PAGER': '/bin/cat', 10:15:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:14 INFO - 'PWD': '/builds/slave/test', 10:15:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:14 INFO - 'SHELL': '/bin/bash', 10:15:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:14 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:14 INFO - 'USER': 'cltbld', 10:15:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:14 INFO - 'XPC_FLAGS': '0x0', 10:15:14 INFO - 'XPC_SERVICE_NAME': '0', 10:15:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:14 INFO - Downloading/unpacking jsonschema==2.5.1 10:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:15:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:16 INFO - Downloading functools32-3.2.3-2.tar.gz 10:15:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:15:16 INFO - warning: no files found matching '*.txt' 10:15:16 INFO - no previously-included directories found matching 'build' 10:15:16 INFO - no previously-included directories found matching 'dist' 10:15:16 INFO - no previously-included directories found matching '.git*' 10:15:16 INFO - Installing collected packages: jsonschema, functools32 10:15:16 INFO - Running setup.py install for functools32 10:15:16 INFO - warning: no files found matching '*.txt' 10:15:16 INFO - no previously-included directories found matching 'build' 10:15:16 INFO - no previously-included directories found matching 'dist' 10:15:16 INFO - no previously-included directories found matching '.git*' 10:15:16 INFO - Successfully installed jsonschema functools32 10:15:16 INFO - Cleaning up... 10:15:16 INFO - Return code: 0 10:15:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:17 INFO - 'HOME': '/Users/cltbld', 10:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:17 INFO - 'LOGNAME': 'cltbld', 10:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:17 INFO - 'MOZ_NO_REMOTE': '1', 10:15:17 INFO - 'NO_EM_RESTART': '1', 10:15:17 INFO - 'PAGER': '/bin/cat', 10:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:17 INFO - 'PWD': '/builds/slave/test', 10:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:17 INFO - 'SHELL': '/bin/bash', 10:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:17 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:17 INFO - 'USER': 'cltbld', 10:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:17 INFO - 'XPC_FLAGS': '0x0', 10:15:17 INFO - 'XPC_SERVICE_NAME': '0', 10:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:15:17 INFO - Cleaning up... 10:15:17 INFO - Return code: 0 10:15:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:18 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:18 INFO - 'HOME': '/Users/cltbld', 10:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:18 INFO - 'LOGNAME': 'cltbld', 10:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:18 INFO - 'MOZ_NO_REMOTE': '1', 10:15:18 INFO - 'NO_EM_RESTART': '1', 10:15:18 INFO - 'PAGER': '/bin/cat', 10:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:18 INFO - 'PWD': '/builds/slave/test', 10:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:18 INFO - 'SHELL': '/bin/bash', 10:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:18 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:18 INFO - 'USER': 'cltbld', 10:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:18 INFO - 'XPC_FLAGS': '0x0', 10:15:18 INFO - 'XPC_SERVICE_NAME': '0', 10:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:18 INFO - Downloading/unpacking blobuploader==1.2.4 10:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:19 INFO - Downloading blobuploader-1.2.4.tar.gz 10:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:15:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:15:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - Downloading docopt-0.6.1.tar.gz 10:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:15:20 INFO - Installing collected packages: blobuploader, requests, docopt 10:15:20 INFO - Running setup.py install for blobuploader 10:15:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:15:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:15:21 INFO - Running setup.py install for requests 10:15:21 INFO - Running setup.py install for docopt 10:15:21 INFO - Successfully installed blobuploader requests docopt 10:15:21 INFO - Cleaning up... 10:15:21 INFO - Return code: 0 10:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:21 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:21 INFO - 'HOME': '/Users/cltbld', 10:15:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:21 INFO - 'LOGNAME': 'cltbld', 10:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:21 INFO - 'MOZ_NO_REMOTE': '1', 10:15:21 INFO - 'NO_EM_RESTART': '1', 10:15:21 INFO - 'PAGER': '/bin/cat', 10:15:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:21 INFO - 'PWD': '/builds/slave/test', 10:15:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:21 INFO - 'SHELL': '/bin/bash', 10:15:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:21 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:21 INFO - 'USER': 'cltbld', 10:15:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:21 INFO - 'XPC_FLAGS': '0x0', 10:15:21 INFO - 'XPC_SERVICE_NAME': '0', 10:15:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-O3O0JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-EchZZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-mqlsmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-NHDvsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UOjMLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-pFONfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-UiK78S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-zOgoO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:22 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-khhR6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-cliw9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-aF8rYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-xd5rX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-gGFhsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-sY71C9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-XKbq0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-FaFCz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:23 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-r9irvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:15:23 INFO - Running setup.py install for manifestparser 10:15:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozcrash 10:15:24 INFO - Running setup.py install for mozdebug 10:15:24 INFO - Running setup.py install for mozdevice 10:15:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozfile 10:15:24 INFO - Running setup.py install for mozhttpd 10:15:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozinfo 10:15:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozInstall 10:15:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for mozleak 10:15:25 INFO - Running setup.py install for mozlog 10:15:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for moznetwork 10:15:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for mozprocess 10:15:25 INFO - Running setup.py install for mozprofile 10:15:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for mozrunner 10:15:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Running setup.py install for mozscreenshot 10:15:26 INFO - Running setup.py install for moztest 10:15:26 INFO - Running setup.py install for mozversion 10:15:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:15:26 INFO - Cleaning up... 10:15:26 INFO - Return code: 0 10:15:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:26 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:26 INFO - 'HOME': '/Users/cltbld', 10:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:26 INFO - 'LOGNAME': 'cltbld', 10:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:26 INFO - 'MOZ_NO_REMOTE': '1', 10:15:26 INFO - 'NO_EM_RESTART': '1', 10:15:26 INFO - 'PAGER': '/bin/cat', 10:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:26 INFO - 'PWD': '/builds/slave/test', 10:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:26 INFO - 'SHELL': '/bin/bash', 10:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:26 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:26 INFO - 'USER': 'cltbld', 10:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:26 INFO - 'XPC_FLAGS': '0x0', 10:15:26 INFO - 'XPC_SERVICE_NAME': '0', 10:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:26 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-poAE7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:26 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-p1XggQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:26 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-l1QAPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-RdaXvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-2LmEcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-tSgraZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-iX8YIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-J1eHHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-KpEHzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-Q07HyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-NbB8Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:27 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-c0M0sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-G2SOxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-e22zYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-ofklze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-NrgL4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:28 INFO - Running setup.py (path:/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/pip-6MuVVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:29 INFO - Downloading blessings-1.6.tar.gz 10:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:15:30 INFO - Installing collected packages: blessings 10:15:30 INFO - Running setup.py install for blessings 10:15:30 INFO - Successfully installed blessings 10:15:30 INFO - Cleaning up... 10:15:30 INFO - Return code: 0 10:15:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:15:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:30 INFO - Reading from file tmpfile_stdout 10:15:30 INFO - Current package versions: 10:15:30 INFO - blessings == 1.6 10:15:30 INFO - blobuploader == 1.2.4 10:15:30 INFO - docopt == 0.6.1 10:15:30 INFO - functools32 == 3.2.3-2 10:15:30 INFO - jsonschema == 2.5.1 10:15:30 INFO - manifestparser == 1.1 10:15:30 INFO - mozInstall == 1.12 10:15:30 INFO - mozcrash == 1.0 10:15:30 INFO - mozdebug == 0.1 10:15:30 INFO - mozdevice == 0.49 10:15:30 INFO - mozfile == 1.2 10:15:30 INFO - mozhttpd == 0.7 10:15:30 INFO - mozinfo == 0.9 10:15:30 INFO - mozleak == 0.1 10:15:30 INFO - mozlog == 3.4 10:15:30 INFO - moznetwork == 0.27 10:15:30 INFO - mozprocess == 0.25 10:15:30 INFO - mozprofile == 0.28 10:15:30 INFO - mozrunner == 6.13 10:15:30 INFO - mozscreenshot == 0.1 10:15:30 INFO - mozsystemmonitor == 0.3 10:15:30 INFO - moztest == 0.8 10:15:30 INFO - mozversion == 1.4 10:15:30 INFO - psutil == 3.1.1 10:15:30 INFO - requests == 1.2.3 10:15:30 INFO - wsgiref == 0.1.2 10:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:30 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:30 INFO - 'HOME': '/Users/cltbld', 10:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:30 INFO - 'LOGNAME': 'cltbld', 10:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:30 INFO - 'MOZ_NO_REMOTE': '1', 10:15:30 INFO - 'NO_EM_RESTART': '1', 10:15:30 INFO - 'PAGER': '/bin/cat', 10:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:30 INFO - 'PWD': '/builds/slave/test', 10:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:30 INFO - 'SHELL': '/bin/bash', 10:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:30 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:30 INFO - 'USER': 'cltbld', 10:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:30 INFO - 'XPC_FLAGS': '0x0', 10:15:30 INFO - 'XPC_SERVICE_NAME': '0', 10:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:15:30 INFO - Cleaning up... 10:15:30 INFO - Return code: 0 10:15:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:30 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 0x10fba6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fba9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd882dabf30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, '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.ypk8HSSfxj/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.iYZraaYXmg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:30 INFO - 'HOME': '/Users/cltbld', 10:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:30 INFO - 'LOGNAME': 'cltbld', 10:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:30 INFO - 'MOZ_NO_REMOTE': '1', 10:15:30 INFO - 'NO_EM_RESTART': '1', 10:15:30 INFO - 'PAGER': '/bin/cat', 10:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:30 INFO - 'PWD': '/builds/slave/test', 10:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:30 INFO - 'SHELL': '/bin/bash', 10:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:30 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:30 INFO - 'USER': 'cltbld', 10:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:30 INFO - 'XPC_FLAGS': '0x0', 10:15:30 INFO - 'XPC_SERVICE_NAME': '0', 10:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:15:31 INFO - Cleaning up... 10:15:31 INFO - Return code: 0 10:15:31 INFO - Running post-action listener: _resource_record_post_action 10:15:31 INFO - Running post-action listener: _start_resource_monitoring 10:15:31 INFO - Starting resource monitoring. 10:15:31 INFO - [mozharness: 2017-05-15 17:15:31.123786Z] Finished create-virtualenv step (success) 10:15:31 INFO - [mozharness: 2017-05-15 17:15:31.124749Z] Running install step. 10:15:31 INFO - Running pre-action listener: _resource_record_pre_action 10:15:31 INFO - Running main action method: install 10:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:31 INFO - Reading from file tmpfile_stdout 10:15:31 INFO - Detecting whether we're running mozinstall >=1.0... 10:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:15:31 INFO - Reading from file tmpfile_stdout 10:15:31 INFO - Output received: 10:15:31 INFO - Usage: mozinstall [options] installer 10:15:31 INFO - Options: 10:15:31 INFO - -h, --help show this help message and exit 10:15:31 INFO - -d DEST, --destination=DEST 10:15:31 INFO - Directory to install application into. [default: 10:15:31 INFO - "/builds/slave/test"] 10:15:31 INFO - --app=APP Application being installed. [default: firefox] 10:15:31 INFO - mkdir: /builds/slave/test/build/application 10:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:15:46 INFO - Reading from file tmpfile_stdout 10:15:46 INFO - Output received: 10:15:46 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:15:46 INFO - Running post-action listener: _resource_record_post_action 10:15:46 INFO - [mozharness: 2017-05-15 17:15:46.228081Z] Finished install step (success) 10:15:46 INFO - [mozharness: 2017-05-15 17:15:46.228191Z] Running run-tests step. 10:15:46 INFO - Running pre-action listener: _resource_record_pre_action 10:15:46 INFO - Running main action method: run_tests 10:15:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:46 INFO - 2017-05-15 10:15:46.242 screenresolution[2887:48930] starting screenresolution argv=screenresolution get 10:15:46 INFO - 2017-05-15 10:15:46.245 screenresolution[2887:48930] Display 0: 1600x1200x32@60 10:15:46 INFO - 2017-05-15 10:15:46.250 screenresolution[2888:48932] starting screenresolution argv=screenresolution list 10:15:46 INFO - Available Modes on Display 0 10:15:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:46 INFO - Intel Iris: 10:15:46 INFO - Chipset Model: Intel Iris 10:15:46 INFO - Type: GPU 10:15:46 INFO - Bus: Built-In 10:15:46 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:46 INFO - Vendor: Intel (0x8086) 10:15:46 INFO - Device ID: 0x0a2e 10:15:46 INFO - Revision ID: 0x0009 10:15:46 INFO - Displays: 10:15:46 INFO - Display: 10:15:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:46 INFO - Display Serial Number: 1600x1200 60 10:15:46 INFO - Main Display: Yes 10:15:46 INFO - Mirror: Off 10:15:46 INFO - Online: Yes 10:15:46 INFO - Rotation: Supported 10:15:46 INFO - Return code: 0 10:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:15:46 INFO - Python 2.7.3 10:15:46 INFO - Return code: 0 10:15:46 INFO - grabbing minidump binary from tooltool 10:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:46 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 0x10f984d30>, '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 0x10f8abdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f979be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:15:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:15:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:15:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:15:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:15:46 INFO - Return code: 0 10:15:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:15:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:15:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:15:46 INFO - ENV: RUST_BACKTRACE is now 1 10:15:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:15:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0276 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ypk8HSSfxj/Render', 10:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:46 INFO - 'HOME': '/Users/cltbld', 10:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:46 INFO - 'LOGNAME': 'cltbld', 10:15:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:15:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:46 INFO - 'MOZ_NO_REMOTE': '1', 10:15:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:15:46 INFO - 'NO_EM_RESTART': '1', 10:15:46 INFO - 'PAGER': '/bin/cat', 10:15:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:46 INFO - 'PWD': '/builds/slave/test', 10:15:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:46 INFO - 'RUST_BACKTRACE': '1', 10:15:46 INFO - 'SHELL': '/bin/bash', 10:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iYZraaYXmg/Listeners', 10:15:46 INFO - 'TMPDIR': '/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/', 10:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:46 INFO - 'USER': 'cltbld', 10:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:46 INFO - 'XPC_FLAGS': '0x0', 10:15:46 INFO - 'XPC_SERVICE_NAME': '0', 10:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0276', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:15:46 INFO - mozversion application_buildid: 20170515090825 10:15:46 INFO - mozversion application_changeset: b0a01d9a55898e9cdaa3a544c8183da2141199fb 10:15:46 INFO - mozversion application_display_name: Firefox 10:15:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:15:46 INFO - mozversion application_name: Firefox 10:15:46 INFO - mozversion application_remotingname: firefox 10:15:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:46 INFO - mozversion application_vendor: Mozilla 10:15:46 INFO - mozversion application_version: 54.0 10:15:46 INFO - mozversion platform_buildid: 20170515090825 10:15:46 INFO - mozversion platform_changeset: b0a01d9a55898e9cdaa3a544c8183da2141199fb 10:15:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:46 INFO - mozversion platform_version: 54.0 10:15:46 INFO - using testdate: 1494868546 10:15:46 INFO - actual date: 1494868546 10:15:46 INFO - starting webserver on 'localhost:61271' 10:15:46 INFO - SUITE-START | Running 2 tests 10:15:46 INFO - TEST-START | dromaeo_css 10:15:46 INFO - operating with platform_type : mac_ 10:15:46 INFO - Initialising browser for dromaeo_css test... 10:15:47 INFO - TEST-INFO | started process 2898 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmplFXZJP/profile http://localhost:61271/getInfo.html) 10:15:49 INFO - PROCESS | 2898 | __metrics Screen width/height:1600/1200 10:15:49 INFO - PROCESS | 2898 | colorDepth:24 10:15:49 INFO - PROCESS | 2898 | Browser inner width/height: 1024/689 10:15:49 INFO - PROCESS | 2898 | __metrics 10:15:49 INFO - PROCESS | 2898 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:15:49 INFO - PROCESS | 2898 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:15:50 INFO - TEST-INFO | 2898: exit 0 10:15:50 INFO - Browser initialized. 10:15:50 INFO - Running cycle 1/1 for dromaeo_css test... 10:15:50 INFO - TEST-INFO | started process 2902 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmplFXZJP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:02 INFO - PROCESS | 2902 | RSS: Main: 165154816 10:16:02 INFO - PROCESS | 2902 | 10:19:06 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:61271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61271/tests/dromaeo/cssquery-ext.html) 10:19:08 INFO - PROCESS | 2902 | RSS: Main: 366350336 10:19:08 INFO - PROCESS | 2902 | 10:22:11 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:61271/tests/dromaeo/cssquery-ext.html (next: http://localhost:61271/tests/dromaeo/cssquery-jquery.html) 10:22:11 INFO - PROCESS | 2902 | RSS: Main: 299876352 10:22:11 INFO - PROCESS | 2902 | 10:25:14 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:61271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61271/tests/dromaeo/cssquery-mootools.html) 10:25:14 INFO - PROCESS | 2902 | RSS: Main: 288473088 10:25:14 INFO - PROCESS | 2902 | 10:28:17 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:61271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61271/tests/dromaeo/cssquery-prototype.html) 10:28:17 INFO - PROCESS | 2902 | RSS: Main: 279461888 10:28:17 INFO - PROCESS | 2902 | 10:31:21 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:61271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61271/tests/dromaeo/cssquery-yui.html) 10:31:21 INFO - PROCESS | 2902 | RSS: Main: 276729856 10:31:21 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | Cycle 1(1): loaded http://localhost:61271/tests/dromaeo/cssquery-yui.html (next: http://localhost:61271/tests/dromaeo/cssquery-dojo.html) 10:34:25 INFO - PROCESS | 2902 | RSS: Main: 313606144 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | __start_tp_report 10:34:25 INFO - PROCESS | 2902 | _x_x_mozilla_page_load 10:34:25 INFO - PROCESS | 2902 | _x_x_mozilla_page_load_details 10:34:25 INFO - PROCESS | 2902 | |i|pagename|runs| 10:34:25 INFO - PROCESS | 2902 | |0;dojo.html;2998.880044798208;3560.679538841504;3572.69632081273;3581.48068530063;3604.0629436346558;6238.282597501288;6270.65511396873;6283.591566548177;6284.811455656338;6292.307846136921;6227.532935029868;6285.245770507534;6288.402601752826;6291.811245662626;6303.527235457336;5531.446855314466;5586.692731899744;5624.55003599711;5656.830295091154;5732.168835518868;5515.7242137893145;5518.255035570187;5561.499465048145;5705.74324155414;5723.027085395472;5622.662640241578;5627.915581266264;5641.943580564182;5674.460926212003;5720.256366672327;5697.31632204134;5705.258316418881;5709.857253568653;5713.914291285634;5716.742746576372;5400.567954563626;5541.5289700375515;5632.662040277596;5643.068893632546;5715.971420142914;6132.754689812402;6159.661218632973;6175;6180.289266734293;6207.34822843604;6556.311587283314;6577.177852768404;6581.04574836646;6585.506087043491;6589.538732288742;6602.504812139109;6656.56732312403;6678.833029174261;6703.1285932828905;6730.865382692367;6167.352427995085;6195.473384762256;6213.471854892295;6243.906341404882;6361.268454127789;6728.764493242713;6738.932610673928;6744.6627668616375;6745.831354216086;6761.594304341756;6334.5249106317215;6368.458681012074;6384.074309225136;6385.840353991188;6415.550911436155;6325.493960483151;6398.5521013528605;6466.1270728452;6591.077249185118;6619.271880093186;6346.048092786119;6348;6393.104965304861;6419.711112999927;6459.257185423737;6551.410373066352;6566.015097735378;6578.210614726264;6592.70332835014;6596.538242323088;5286.9471305287225;5317.813876514303;5326.014687282865;5343.973280133652;5367.919481207785;4679.508651591602;4688.109259240733;4715.434147902273;4766.45185803637;4782.0914026334885;5173.5343819055715;6190.535709821781;6197.752089916443;6199.225096862892;6200.689965501707;6018.578699491081;6402.103705481236;6486.967565162144;6499.772507962199;6522.412982831568;6281.842953926097;6322.399372059647;6325;6343.841403964845;6344.746210151635;5818.592698511064;5828.417158284137;5844.12338149281;5856.385079566669;5858.765649373977;6525.706343214471;6620.602763834186;6728.259891411843;6894.626100657069;6932.79201623942;5423.457654234545;6473.1261279728515;6484.1894763154605;6508.934910651023;6515.478761698958;6703.497237707289;6740.325967403221;6792.117024786746;6899.413549848319;6944.965275173592;6353.332900045429;6574.539782215199;6607.867842643216;6608.768693095719;6611.570247933869;6379.298277189413;6393.680315984089;6522.05430212607;6702.262751097278;6739.05653208561;6294.622322660655;6372.968135159294;6377.521685873486;6504.804855854142;6534;6333.714982825876;6465;6467.353264673495;6503.089567460654;6505.21937367519;5266.55234305073;6340.1440805490665;6346.238451385863;6352.428281454691;6362.904556431565;6252.718627661857;6284.905726414016;6365.61806291624;6456.192975878015;6486.124373209556;6253.749850005949;6344.492440604833;6355.046742988588;6360.141380913703;6371.904421433775 10:34:25 INFO - PROCESS | 2902 | |1;ext.html;748.1770052941765;815.6818840652145;828.5153185386548;911.3483859040784;915.6291701860748;30062.69937300629;30536.54195187074;41280.793596032054;41722;41965.7901710491;42930;43241.783791081;43284.56715432845;43422.91442713934;43463.78268108663;8876.689315873946;9145.844683584153;9784.315097943147;9793.510324483783;9795.804083918318;7958.283754462097;8114.350851931831;11845.7630847383;12022.458989345456;12329.753404931897;12058.216215945935;12079.577214797484;12109.697257568587;12116.151869369147;12131.150819442642;12046.096542759284;12076.154669173162;12096.697582560419;12099.27404355737;12106.273623582525;11601.941990290079;11644.126690498246;11680.649580512598;11682.299062056303;11697.824532632018;40298.194036119115;40646.59353406487;40651.59348406507;40745.592544074476;40767;4052.959470405288;4084.2852500812537;4094.7952602369764;4105.794710264505;4114.938275925863;28562;29332.853335733183;30601.846990765127;30772;30848.766049357997;3109.967781413127;3292.4732042873266;3292.6378098409155;3330.5004249362546;3391.440412331987;364932;369749;370607;370748;370767.1461642702;43566.78216608897;53670;53858.19212711813;53873.92252154935;54020.729896351055;55595.722021389636;55723.72138139365;55729.44270557324;55750.16374754382;55759.44240557543;15013.69972600542;15317.004394714531;15331.463398780988;15350.539483815503;15358.539243822703;11699.649010529698;13183.14309569875;13189.208647481182;13192.670183245304;13206.405711742995;9844.458554779412;10683.679489615324;10736.463176841127;11794.76410471803;12039.39803009846;5331.493508116722;5353.892922141613;5357.8392648220615;5368.597355198298;5397.919031214535;962.2109869906749;970.9708708738608;973.5764942250144;977.7311239409078;1018.7402212435929;29383.971560995262;29622.13124922466;29662.55506167409;29768.702312977028;30488;846.8137009857821;1104.8011357955645;1123.5786580032488;1157.4039369724599;1168.976620467603;13311.600651980263;13348.93325533366;14026.228557429235;14278.42886284537;14496.275186240646;492417;495713;510765;609240;612333.8766612366;50693;50763.492365076614;50851.47445576639;50866.491335086175;51074;1277.9297138657464;1309.9148555343872;1429.9428022879176;1466.8973171877867;1477.8078849749463;25679.358016049748;27147.59278610783;27473.86263068672;27491.31271718223;27510.724892751616;44796;44947.77526112348;45004;45039.54960450485;45071;474824;476749;477630;477703;479659;23809.404764881016;23953.640695389233;28279.575806363333;28953.85523072371;29081.7091829079;25546.744532554436;30789.846050769604;34424.139396514285;36018.27963440769;36088.8195559031;27701.72298277072;27804.86097569499;27952.860235698692;28287.86848526036;29025.85487072551;27622.171334859988;28089;28236;28252.85873570619;28668.42663146767;32024;32110.35779284448;32614.51078233876;32673.673263268014;32736.83631581827;27686;27904.02335918233;27989;28058.877644893975;28837.27906802347;21893.890530547244;22351.44121396978;22811.771882281628;22904.969276382937;22976.655350170095 10:34:25 INFO - PROCESS | 2902 | |2;jquery.html;424.4566954298495;426.60965216826605;438.90563528841295;447.23299541286684;448.654037886341;21577.352679419622;21982;22550.548989020208;22793;22794.65808012879;18823.529411764714;23574.764252357472;24182.75817241827;24436.266911992625;24674.0130394784;6698.196216454033;6807.387335139824;6863.450923926074;7022.016917631536;7077.539959902616;6812.931870681304;6947.652617369136;6950.617716025617;6963.05998690178;7323.926760732405;7700;7787.182345853689;7790.571518566477;7791.181925897784;7804.1415444301365;7549.35830454412;7645.885311720329;7668.38652907766;7727.343175830061;7742.380609551279;7281.599512026836;7470.2903224193615;7484.700611975514;7502.924970750332;7552.66013029415;25953.870230648725;26113.347166320804;26156.73843261581;26272.211833645022;26765.732342676518;2825.731555502224;3056.816591004547;3186.8725250989933;3223.9677603223904;3231.3698828728407;13240.271785051818;16032.839671603251;17197.484075477754;17216.31134754608;17362.913185434118;2120.586485635302;2162.2648299578063;2185.868847869117;2233.8883055847305;2274.431392151962;153634.9999999989;168063.15968420202;177461.112694437;180550;181288.18711812844;16338.836611633731;19395.709064364044;20731.689024664644;20913.26803561868;20962.161513539286;32564.86022989184;32945.176370590445;33082.8345858274;33084.3383132336;33104.8413305538;10272.589096436059;10422.791544169075;10532.842007369896;10761;10916.563337466412;6908.6545672716165;8171.387145964077;8969.282457403522;8980.236679882284;9177.174054335143;9441.763955901157;9537.570809313598;9595.568199431042;9606.231501479864;9651.420914745138;3324.7838890472044;4014.538328092307;4021.481203946547;4074.8794081627198;4081.1837632473625;640.2956747577628;763.7975936376864;774.3688893551737;777.0985656638272;796.8924195233569;19553;22402.439939001655;22559.43601409945;22559.661605075937;22592;891.4027601506876;897.1448636725564;913.1188403190953;914.7484441778564;923.3444254579324;10765.03114719679;11574.247673901169;11603.36181510025;11619.941900290445;11634.36011019402;658384.4161558324;746818;775523;779540;807658;161248.58127128103;162627;166680;167155.16422418054;175390;1222.49877550204;1244.1788419642992;1284.2487145020082;1285.460106755165;1288.0275392079109;19026.7145992813;19054.95197764136;19057.71413428772;19186.040697965323;19346.903265483583;62166.68916655388;62732.37267627265;63421.682891585246;63846.7230655375;65878.01182982163;89867;90746.54626726825;91284;92914;93031.53484232536;11381.829272560753;11397.316161030365;11700.648980530597;11915.582954596548;11919.880801191877;12794.616161515169;12890.162139460905;12977.675558111123;12979.89670877986;13020.739585208432;10889.0199882011;11021.779564408827;11204.66386008421;11229.4385280734;11366.43167841588;10373.740656483647;10464.790704186025;10497.52761125736;10805.405702686428;10824.188185885932;11471.541138354372;11644.41777911118;11662;11702.765944680888;11811.763764724485;11449.771004580029;11489.138314626269;11676.94161529221;11698.76602467963;11701;9624.711258662252;11316.98147166759;11379.601713939972;11414.942925285653;11418.942905285421 10:34:25 INFO - PROCESS | 2902 | |3;mootools.html;1615.6284054667433;1619.4251040880488;1628.096406494396;1646.119326160504;1660.6180578466951;10971.561137554498;11644.941775291132;11653.126015548825;11657.650270491888;11672.299662020274;10976.506057227423;11325.603603873866;11326.716832079203;11537.480813363398;11553.075753939685;2401.86789726565;2449.1917667169882;2450.3092865922545;2453.50929814037;2461.2985299189736;2350.4006478348083;2423.1761201191593;2424.75752424757;2429.4898071404937;2433.07543133609;1972.2801177570154;2415.10641062807;2420.891059902309;2422.842515236513;2427.526632306701;1958.9412317630495;2326.0579465316405;2396.8322217444697;2403.51929614077;2410.7830295273334;1959.5590992026823;2112.1973650012897;2253.523941818112;2326.9883650581637;2399.9520009599887;11424.942875285571;11516.654500365003;11520.884791152148;11538.94230528842;11540.192186546945;5629.6340737852315;5653.321601407816;5866.560007999375;5869.941300587025;5937.614055086406;3316.7844090134304;3487.1630808606005;3520.8591656333715;3533.5583052118486;3557.2707594942976;1218.2507757729022;1262.8421447319085;1292.1923797626484;1295.6307452376122;1312.376621104977;13897.79153312701;14116.647083822883;14558.708825823423;15699.215039248105;15933.203339833079;3697.0757310672334;4291.442112525352;4541.9545804542195;4679.181143299909;4691.202495575761;12260.754784904251;12282.447289871798;12288.815667764991;12289.754204916031;12293.877061229274;4906.8773280668265;5117.078925793318;5157.355330583677;5158.793648254103;5719.5996280260815;4507.549245075532;5158.277841102249;5321.627486076015;5336.092864213093;5337.8131765388025;3824.330742120118;3924.843006279717;3965.960340396559;3970;3974.7615143091507;853.9931161769915;1008.3748076192661;1018.3686114608989;1030.3766221436074;1037.4605205293205;809.6073404398862;825.8141918068386;838.4678271619674;859.0421679826948;895.2569367425021;6438.871222575523;6741.764038258638;6873.931260687429;6892.896606550906;6986.37122658953;1034.425893629028;1040.6929955663097;1054.6097943760703;1063.3938654966594;1073.8067147913293;3644.526211592529;3650.7444478886755;4220.704550681423;4277.230098582223;4309.612134907873;11552.59565915189;13589;14109.85890141106;14204.502842400467;14216.218108003952;13609.795853062213;13768.862311376959;14066.71866562663;14132.858671413154;14160.150390976574;9973.950130249303;10337.276390652582;10534.736631584272;10548.103411209979;10584.57661693539;7003.929960700328;7008.369246767816;7137.750178743719;7315.926840731525;7320.9267907320245;12149.453274602842;12575.811362829381;12641;12656.114072015238;12787.232766034067;11548.01841843419;11594.88405115938;12986.545470908473;13015.739685206054;13114.344282785632;9144.3598948073;9268.490233037248;9526;9634.807303854024;9651.9034809651;10419.635312764018;10515.894841051491;10583.735406614896;10634.946825265824;10642.521086550969;9059.003509613805;9109.453432794053;9112.453252804853;9138.908610913806;9265.397749146285;8876.334274929277;8893.244074253502;9037.277017838425;9067.90932090697;9084.273258139465;8165.591720413837;8178.386621003568;8193.631286591999;8275.420720549744;8283.17168283167;6029.095635654687;6059.545534084873;6067.787627433018;6097.237845269341;6170.6914654268;6094.75620975156;6099.8780024398375;6166.229221347332;6231.096491008688;6253.187085678833 10:34:25 INFO - PROCESS | 2902 | |4;prototype.html;327.90326853578193;373.09338307911764;396.3954968672775;399.58443219052185;401.17558417452125;4946.727929963855;5065.620078494114;5253.4221235664045;5399.246384343984;5417.59368047397;4825.927611085836;5153.60852569806;5200.765965531551;5201.947980520194;5250.00999240577;3924.9803750981346;4010.6189911958318;4126.968257935516;4245.9575404246025;4391.913840925567;3555.6266592007855;3946.815955213439;4049.484824420764;4221.409002739619;4371.803268852909;3845.2117315950163;4032.354823228284;4077.245709543744;4399.5160532341415;4419.801108950089;4120.423140760296;4237.809298581571;4317.373980772802;4331.913361732779;4372.103718737651;3989.92272086535;3995.840166393341;4006.759594424315;4007.0966291011828;4010.7994600270163;4710.481846996862;5015.84952451427;5115.411727651293;5153.433122356538;5178.275041494194;4152.712659075696;4356.8257269709175;4433.556644335573;4824.396950381202;4898.848770538921;4844.515548445127;4954.801807927714;5383.896301258233;5565.749541270612;5566.860828479321;3826.617338266179;4017.658499027586;4062.923325318793;4484.618807401343;4522.773861306921;5274.683518988873;5476.795105076877;5555.194691636242;5692.747595528977;6000.669963152025;3787.4318852172396;3997.780122093313;4037.707933461264;4155.709100363006;4352.434183556117;4229.281022226168;4330.376108959141;4390.385346051556;4565.520620334817;4591.724496530219;4223.303154979455;4580.335851301543;4956.677815941952;4985.376827896513;5007.774650140753;4675.462321832964;4942.332785074041;5255.921161182585;5568;5582.804601838916;1354.3296068446182;1492.843251458603;1524.8703860171788;1572.716910956016;1610.9758353624704;1035.77213013137;1108.9465008242203;1131.9151063670258;1196.629044996054;1216.367488905764;4452.510223875371;4939.678920870204;5261.631685781958;5268.683878967274;5300.787968481295;5445.401005889349;5759.848030393938;5796.797217057491;5800.709964501758;5907.911381329283;4586.954130458719;5286.36563612369;5297.496737809902;5332.440093790174;5334.946650533522;2870.971290287112;2875.8274503529856;2902.201894478993;3002.8198308101582;3004.414139242827;5265.394479634814;5421.918671219856;5899.616524925866;5924.7333869975955;6012.414661695513;5062.9622226663705;5656.8020119295625;5755.683437410982;5799.681017543991;5906.2912450505355;4576.057857513811;5753.769849206069;5807.535397168216;5883.999720047602;6016.368281330485;5458.8908221834545;5599.664020158803;5760.724513816935;5778.91958894806;5780.046292361713;5254.00173966945;5450.945490545202;5486.31421072366;5544.778208871761;5704.458076482728;5973.193618861572;5983.700814959322;5990.981533139289;6000.369961154191;6015.841683116399;4405.581469760367;4569.383133277098;4571.657125715518;4593.747343896118;4609.677322587387;5217.8173763918085;5622.775088996558;5733.308673941114;5742.502011624956;5755.5683323750045;5234.691327168208;5340.973295133655;5396.679427846064;5562.582806289463;5597.720113994365;5149.665271757473;5352.805349806561;5478.91781623284;5496.7526461310135;5609.607327487037;5457.672539647634;5470;5480.890382192254;5497.45025497447;5531.032069387921;5021.949780502148;5253.213907271546;5326.14781634952;5605.355384130714;5615.298087739033;4798.352222449925;4821.566059054702;4915.459299476983;5270.156774916149;5380.892382152413 10:34:25 INFO - PROCESS | 2902 | |5;yui.html;734.9191588925214;756.1228974389709;780.8087018680424;866.402182494079;885.5882014863088;16282;17110.486685399443;17132.571685707862;17164.656706865888;17180.742288865677;15724.685506289867;15905.284262208199;15923.442679506219;15940.920295398535;15950.28223729932;977.5454413697629;983.6311383231289;990.7176454710409;1008.3949630221881;1023.0587859169544;1003.9799204015916;1014.9391036537794;1023.2581378500602;1023.8105950399163;1026.2405819693447;1063.3035361838008;1065.2809353686268;1072.9731756706067;1073.2809017957986;1077.0091515805477;1062.984055239172;1063.9627613033583;1064.5316060933164;1066.594694016269;1067.685032915292;798.660569258063;799.5402643479964;799.8480288745178;800.5476905548405;804.252045597594;16458;16543.586410339718;17048.82951170497;17892.194851231725;18144.727829082574;14439.35022923971;14509.782353264709;14593.48922787708;14603.123812571173;14619.561413157622;10457.163426925828;11753.4710938008;11811.822822657581;11837.822432663517;11943.761124777542;3470.774399664014;3586.3053913595472;3795.6773674237447;3797.35444974355;3817.1793064491267;110499.44750276198;113835.43082284536;118843.24627014909;118890.40554797256;120382.398088009;11007.504662290215;11129.888701113046;11258.549658013751;11348.659540213806;11500.079993600491;22770.43073923165;23191;23226.53546929052;23240.535189296457;23241.3027609172;7828.882566761503;7829.725959591501;7867.370610351159;7998.080220774569;8134.633941472648;4462;4507.526709695435;4568.520305367889;4637.791299391535;4654.2785868190485;2918.5184444566835;3397.405454045532;3578.8210589470423;3581.122624956902;3583.964160358415;625.6590158363703;626.9185005949289;635.7806556737918;638.3712043637014;645.9547831651739;670.9563878347892;672.8015235505537;690.547691262224;701.8631366883409;730.3098571849551;9929.3545919515;10348.586056557804;10370.637027704144;10502.947485262677;10707;684.2507454337492;692.1071817355655;700.4291502425507;702.9578225306498;707.3987110955646;4969.2546118081855;5083.729067733067;5089.262057001714;5090.211017292326;5094.133997220481;107457;109724;112695;112867;115321.42339288417;92952;94605.52697236469;94607.0539294612;95628;96482;78438.6078069606;79234;80531.59734201408;81641.9999999988;81650;9739.071635973258;9825.213982881209;9828.606855725977;9862.654807081717;10388.902650483033;55358;55984;56641.71679141743;56650.43349566452;57143;68919.65540172267;68944;69112.65443672749;70158;70725.64637176781;9172.266218702354;11414.771704566028;11677.357745324089;11748.236364636272;11773.941130294294;12762.234265944073;12972.28652424088;13104.541341053018;13262.288900795686;13782.966277529425;11741.178117532032;12294.815577766161;12446.066545009342;12457.501699931901;12750.553730619384;10370.377777333384;11591;11760.76478470443;12281.38593070325;12414.565490207799;11794.410279485819;12322;12416.565420210249;12611.621651350475;12711.80932285998;9135.817283654422;9348.532573371169;9412.711582315009;9460.763480913032;9584.9041509584;10307.381557106597;10381.896181038093;10386;10391.532381043022;10400.947995260278 10:34:25 INFO - PROCESS | 2902 | __end_tp_report 10:34:25 INFO - PROCESS | 2902 | __start_cc_report 10:34:25 INFO - PROCESS | 2902 | _x_x_mozilla_cycle_collect,2066 10:34:25 INFO - PROCESS | 2902 | __end_cc_report 10:34:25 INFO - PROCESS | 2902 | __startTimestamp1494869665307__endTimestamp 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | ------- Summary: start ------- 10:34:25 INFO - PROCESS | 2902 | Number of tests: 6 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | [#0] dojo.html Cycles:165 Average:6111.45 Median:6340.14 stddev:671.73 (10.6%) stddev-sans-first:627.85 10:34:25 INFO - PROCESS | 2902 | Values: 2998.9 3560.7 3572.7 3581.5 3604.1 6238.3 6270.7 6283.6 6284.8 6292.3 6227.5 6285.2 6288.4 6291.8 6303.5 5531.4 5586.7 5624.6 5656.8 5732.2 5515.7 5518.3 5561.5 5705.7 5723.0 5622.7 5627.9 5641.9 5674.5 5720.3 5697.3 5705.3 5709.9 5713.9 5716.7 5400.6 5541.5 5632.7 5643.1 5716.0 6132.8 6159.7 6175.0 6180.3 6207.3 6556.3 6577.2 6581.0 6585.5 6589.5 6602.5 6656.6 6678.8 6703.1 6730.9 6167.4 6195.5 6213.5 6243.9 6361.3 6728.8 6738.9 6744.7 6745.8 6761.6 6334.5 6368.5 6384.1 6385.8 6415.6 6325.5 6398.6 6466.1 6591.1 6619.3 6346.0 6348.0 6393.1 6419.7 6459.3 6551.4 6566.0 6578.2 6592.7 6596.5 5286.9 5317.8 5326.0 5344.0 5367.9 4679.5 4688.1 4715.4 4766.5 4782.1 5173.5 6190.5 6197.8 6199.2 6200.7 6018.6 6402.1 6487.0 6499.8 6522.4 6281.8 6322.4 6325.0 6343.8 6344.7 5818.6 5828.4 5844.1 5856.4 5858.8 6525.7 6620.6 6728.3 6894.6 6932.8 5423.5 6473.1 6484.2 6508.9 6515.5 6703.5 6740.3 6792.1 6899.4 6945.0 6353.3 6574.5 6607.9 6608.8 6611.6 6379.3 6393.7 6522.1 6702.3 6739.1 6294.6 6373.0 6377.5 6504.8 6534.0 6333.7 6465.0 6467.4 6503.1 6505.2 5266.6 6340.1 6346.2 6352.4 6362.9 6252.7 6284.9 6365.6 6456.2 6486.1 6253.7 6344.5 6355.0 6360.1 6371.9 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | [#1] ext.html Cycles:180 Average:59074.89 Median:16979.37 stddev:125369.21 (738.4%) stddev-sans-first:125644.41 10:34:25 INFO - PROCESS | 2902 | Values: 748.2 815.7 828.5 911.3 915.6 30062.7 30536.5 41280.8 41722.0 41965.8 42930.0 43241.8 43284.6 43422.9 43463.8 8876.7 9145.8 9784.3 9793.5 9795.8 7958.3 8114.4 11845.8 12022.5 12329.8 12058.2 12079.6 12109.7 12116.2 12131.2 12046.1 12076.2 12096.7 12099.3 12106.3 11601.9 11644.1 11680.6 11682.3 11697.8 40298.2 40646.6 40651.6 40745.6 40767.0 4053.0 4084.3 4094.8 4105.8 4114.9 28562.0 29332.9 30601.8 30772.0 30848.8 3110.0 3292.5 3292.6 3330.5 3391.4 364932.0 369749.0 370607.0 370748.0 370767.1 43566.8 53670.0 53858.2 53873.9 54020.7 55595.7 55723.7 55729.4 55750.2 55759.4 15013.7 15317.0 15331.5 15350.5 15358.5 11699.6 13183.1 13189.2 13192.7 13206.4 9844.5 10683.7 10736.5 11794.8 12039.4 5331.5 5353.9 5357.8 5368.6 5397.9 962.2 971.0 973.6 977.7 1018.7 29384.0 29622.1 29662.6 29768.7 30488.0 846.8 1104.8 1123.6 1157.4 1169.0 13311.6 13348.9 14026.2 14278.4 14496.3 492417.0 495713.0 510765.0 609240.0 612333.9 50693.0 50763.5 50851.5 50866.5 51074.0 1277.9 1309.9 1429.9 1466.9 1477.8 25679.4 27147.6 27473.9 27491.3 27510.7 44796.0 44947.8 45004.0 45039.5 45071.0 474824.0 476749.0 477630.0 477703.0 479659.0 23809.4 23953.6 28279.6 28953.9 29081.7 25546.7 30789.8 34424.1 36018.3 36088.8 27701.7 27804.9 27952.9 28287.9 29025.9 27622.2 28089.0 28236.0 28252.9 28668.4 32024.0 32110.4 32614.5 32673.7 32736.8 27686.0 27904.0 27989.0 28058.9 28837.3 21893.9 22351.4 22811.8 22905.0 22976.7 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | [#2] jquery.html Cycles:180 Average:44422.89 Median:23878.76 stddev:126781.07 (530.9%) stddev-sans-first:127093.68 10:34:25 INFO - PROCESS | 2902 | Values: 424.5 426.6 438.9 447.2 448.7 21577.4 21982.0 22550.5 22793.0 22794.7 18823.5 23574.8 24182.8 24436.3 24674.0 6698.2 6807.4 6863.5 7022.0 7077.5 6812.9 6947.7 6950.6 6963.1 7323.9 7700.0 7787.2 7790.6 7791.2 7804.1 7549.4 7645.9 7668.4 7727.3 7742.4 7281.6 7470.3 7484.7 7502.9 7552.7 25953.9 26113.3 26156.7 26272.2 26765.7 2825.7 3056.8 3186.9 3224.0 3231.4 13240.3 16032.8 17197.5 17216.3 17362.9 2120.6 2162.3 2185.9 2233.9 2274.4 153635.0 168063.2 177461.1 180550.0 181288.2 16338.8 19395.7 20731.7 20913.3 20962.2 32564.9 32945.2 33082.8 33084.3 33104.8 10272.6 10422.8 10532.8 10761.0 10916.6 6908.7 8171.4 8969.3 8980.2 9177.2 9441.8 9537.6 9595.6 9606.2 9651.4 3324.8 4014.5 4021.5 4074.9 4081.2 640.3 763.8 774.4 777.1 796.9 19553.0 22402.4 22559.4 22559.7 22592.0 891.4 897.1 913.1 914.7 923.3 10765.0 11574.2 11603.4 11619.9 11634.4 658384.4 746818.0 775523.0 779540.0 807658.0 161248.6 162627.0 166680.0 167155.2 175390.0 1222.5 1244.2 1284.2 1285.5 1288.0 19026.7 19055.0 19057.7 19186.0 19346.9 62166.7 62732.4 63421.7 63846.7 65878.0 89867.0 90746.5 91284.0 92914.0 93031.5 11381.8 11397.3 11700.6 11915.6 11919.9 12794.6 12890.2 12977.7 12979.9 13020.7 10889.0 11021.8 11204.7 11229.4 11366.4 10373.7 10464.8 10497.5 10805.4 10824.2 11471.5 11644.4 11662.0 11702.8 11811.8 11449.8 11489.1 11676.9 11698.8 11701.0 9624.7 11317.0 11379.6 11414.9 11418.9 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | [#3] mootools.html Cycles:180 Average:6844.05 Median:2426.14 stddev:4325.95 (178.3%) stddev-sans-first:4320.25 10:34:25 INFO - PROCESS | 2902 | Values: 1615.6 1619.4 1628.1 1646.1 1660.6 10971.6 11644.9 11653.1 11657.7 11672.3 10976.5 11325.6 11326.7 11537.5 11553.1 2401.9 2449.2 2450.3 2453.5 2461.3 2350.4 2423.2 2424.8 2429.5 2433.1 1972.3 2415.1 2420.9 2422.8 2427.5 1958.9 2326.1 2396.8 2403.5 2410.8 1959.6 2112.2 2253.5 2327.0 2400.0 11424.9 11516.7 11520.9 11538.9 11540.2 5629.6 5653.3 5866.6 5869.9 5937.6 3316.8 3487.2 3520.9 3533.6 3557.3 1218.3 1262.8 1292.2 1295.6 1312.4 13897.8 14116.6 14558.7 15699.2 15933.2 3697.1 4291.4 4542.0 4679.2 4691.2 12260.8 12282.4 12288.8 12289.8 12293.9 4906.9 5117.1 5157.4 5158.8 5719.6 4507.5 5158.3 5321.6 5336.1 5337.8 3824.3 3924.8 3966.0 3970.0 3974.8 854.0 1008.4 1018.4 1030.4 1037.5 809.6 825.8 838.5 859.0 895.3 6438.9 6741.8 6873.9 6892.9 6986.4 1034.4 1040.7 1054.6 1063.4 1073.8 3644.5 3650.7 4220.7 4277.2 4309.6 11552.6 13589.0 14109.9 14204.5 14216.2 13609.8 13768.9 14066.7 14132.9 14160.2 9974.0 10337.3 10534.7 10548.1 10584.6 7003.9 7008.4 7137.8 7315.9 7320.9 12149.5 12575.8 12641.0 12656.1 12787.2 11548.0 11594.9 12986.5 13015.7 13114.3 9144.4 9268.5 9526.0 9634.8 9651.9 10419.6 10515.9 10583.7 10634.9 10642.5 9059.0 9109.5 9112.5 9138.9 9265.4 8876.3 8893.2 9037.3 9067.9 9084.3 8165.6 8178.4 8193.6 8275.4 8283.2 6029.1 6059.5 6067.8 6097.2 6170.7 6094.8 6099.9 6166.2 6231.1 6253.2 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | [#4] prototype.html Cycles:180 Average:4636.65 Median:5042.46 stddev:1307.05 (25.9%) stddev-sans-first:1270.08 10:34:25 INFO - PROCESS | 2902 | Values: 327.9 373.1 396.4 399.6 401.2 4946.7 5065.6 5253.4 5399.2 5417.6 4825.9 5153.6 5200.8 5201.9 5250.0 3925.0 4010.6 4127.0 4246.0 4391.9 3555.6 3946.8 4049.5 4221.4 4371.8 3845.2 4032.4 4077.2 4399.5 4419.8 4120.4 4237.8 4317.4 4331.9 4372.1 3989.9 3995.8 4006.8 4007.1 4010.8 4710.5 5015.8 5115.4 5153.4 5178.3 4152.7 4356.8 4433.6 4824.4 4898.8 4844.5 4954.8 5383.9 5565.7 5566.9 3826.6 4017.7 4062.9 4484.6 4522.8 5274.7 5476.8 5555.2 5692.7 6000.7 3787.4 3997.8 4037.7 4155.7 4352.4 4229.3 4330.4 4390.4 4565.5 4591.7 4223.3 4580.3 4956.7 4985.4 5007.8 4675.5 4942.3 5255.9 5568.0 5582.8 1354.3 1492.8 1524.9 1572.7 1611.0 1035.8 1108.9 1131.9 1196.6 1216.4 4452.5 4939.7 5261.6 5268.7 5300.8 5445.4 5759.8 5796.8 5800.7 5907.9 4587.0 5286.4 5297.5 5332.4 5334.9 2871.0 2875.8 2902.2 3002.8 3004.4 5265.4 5421.9 5899.6 5924.7 6012.4 5063.0 5656.8 5755.7 5799.7 5906.3 4576.1 5753.8 5807.5 5884.0 6016.4 5458.9 5599.7 5760.7 5778.9 5780.0 5254.0 5450.9 5486.3 5544.8 5704.5 5973.2 5983.7 5991.0 6000.4 6015.8 4405.6 4569.4 4571.7 4593.7 4609.7 5217.8 5622.8 5733.3 5742.5 5755.6 5234.7 5341.0 5396.7 5562.6 5597.7 5149.7 5352.8 5478.9 5496.8 5609.6 5457.7 5470.0 5480.9 5497.5 5531.0 5021.9 5253.2 5326.1 5605.4 5615.3 4798.4 4821.6 4915.5 5270.2 5380.9 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - PROCESS | 2902 | [#5] yui.html Cycles:180 Average:21472.26 Median:20457.58 stddev:31731.68 (155.1%) stddev-sans-first:31782.49 10:34:25 INFO - PROCESS | 2902 | Values: 734.9 756.1 780.8 866.4 885.6 16282.0 17110.5 17132.6 17164.7 17180.7 15724.7 15905.3 15923.4 15940.9 15950.3 977.5 983.6 990.7 1008.4 1023.1 1004.0 1014.9 1023.3 1023.8 1026.2 1063.3 1065.3 1073.0 1073.3 1077.0 1063.0 1064.0 1064.5 1066.6 1067.7 798.7 799.5 799.8 800.5 804.3 16458.0 16543.6 17048.8 17892.2 18144.7 14439.4 14509.8 14593.5 14603.1 14619.6 10457.2 11753.5 11811.8 11837.8 11943.8 3470.8 3586.3 3795.7 3797.4 3817.2 110499.4 113835.4 118843.2 118890.4 120382.4 11007.5 11129.9 11258.5 11348.7 11500.1 22770.4 23191.0 23226.5 23240.5 23241.3 7828.9 7829.7 7867.4 7998.1 8134.6 4462.0 4507.5 4568.5 4637.8 4654.3 2918.5 3397.4 3578.8 3581.1 3584.0 625.7 626.9 635.8 638.4 646.0 671.0 672.8 690.5 701.9 730.3 9929.4 10348.6 10370.6 10502.9 10707.0 684.3 692.1 700.4 703.0 707.4 4969.3 5083.7 5089.3 5090.2 5094.1 107457.0 109724.0 112695.0 112867.0 115321.4 92952.0 94605.5 94607.1 95628.0 96482.0 78438.6 79234.0 80531.6 81642.0 81650.0 9739.1 9825.2 9828.6 9862.7 10388.9 55358.0 55984.0 56641.7 56650.4 57143.0 68919.7 68944.0 69112.7 70158.0 70725.6 9172.3 11414.8 11677.4 11748.2 11773.9 12762.2 12972.3 13104.5 13262.3 13783.0 11741.2 12294.8 12446.1 12457.5 12750.6 10370.4 11591.0 11760.8 12281.4 12414.6 11794.4 12322.0 12416.6 12611.6 12711.8 9135.8 9348.5 9412.7 9460.8 9584.9 10307.4 10381.9 10386.0 10391.5 10400.9 10:34:25 INFO - PROCESS | 2902 | -------- Summary: end -------- 10:34:25 INFO - PROCESS | 2902 | 10:34:25 INFO - TEST-INFO | 2902: exit 0 10:34:25 INFO - TEST-OK | dromaeo_css | took 1119086ms 10:34:25 INFO - TEST-START | kraken 10:34:25 INFO - operating with platform_type : mac_ 10:34:25 INFO - Initialising browser for kraken test... 10:34:25 INFO - TEST-INFO | started process 2907 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpdZmDJS/profile http://localhost:61271/getInfo.html) 10:34:28 INFO - PROCESS | 2907 | __metrics Screen width/height:1600/1200 10:34:28 INFO - PROCESS | 2907 | colorDepth:24 10:34:28 INFO - PROCESS | 2907 | Browser inner width/height: 1024/689 10:34:28 INFO - PROCESS | 2907 | __metrics 10:34:28 INFO - PROCESS | 2907 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:34:28 INFO - PROCESS | 2907 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:34:29 INFO - TEST-INFO | 2907: exit 0 10:34:29 INFO - Browser initialized. 10:34:29 INFO - Running cycle 1/1 for kraken test... 10:34:29 INFO - TEST-INFO | started process 2910 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/T/tmpdZmDJS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:34:41 INFO - PROCESS | 2910 | RSS: Main: 165048320 10:34:41 INFO - PROCESS | 2910 | 10:34:43 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61271/tests/kraken/driver.html?testName=audio-beat-detection) 10:34:44 INFO - PROCESS | 2910 | RSS: Main: 215695360 10:34:44 INFO - PROCESS | 2910 | 10:34:47 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61271/tests/kraken/driver.html?testName=audio-dft) 10:34:47 INFO - PROCESS | 2910 | RSS: Main: 231374848 10:34:47 INFO - PROCESS | 2910 | 10:34:51 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61271/tests/kraken/driver.html?testName=audio-fft) 10:34:51 INFO - PROCESS | 2910 | RSS: Main: 328986624 10:34:51 INFO - PROCESS | 2910 | 10:34:53 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61271/tests/kraken/driver.html?testName=audio-oscillator) 10:34:54 INFO - PROCESS | 2910 | RSS: Main: 230334464 10:34:54 INFO - PROCESS | 2910 | 10:34:56 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:34:56 INFO - PROCESS | 2910 | RSS: Main: 231682048 10:34:56 INFO - PROCESS | 2910 | 10:34:59 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61271/tests/kraken/driver.html?testName=imaging-darkroom) 10:35:00 INFO - PROCESS | 2910 | RSS: Main: 271904768 10:35:00 INFO - PROCESS | 2910 | 10:35:03 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61271/tests/kraken/driver.html?testName=imaging-desaturate) 10:35:04 INFO - PROCESS | 2910 | RSS: Main: 273813504 10:35:04 INFO - PROCESS | 2910 | 10:35:06 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61271/tests/kraken/driver.html?testName=json-parse-financial) 10:35:07 INFO - PROCESS | 2910 | RSS: Main: 271433728 10:35:07 INFO - PROCESS | 2910 | 10:35:09 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:35:09 INFO - PROCESS | 2910 | RSS: Main: 246894592 10:35:09 INFO - PROCESS | 2910 | 10:35:11 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:35:12 INFO - PROCESS | 2910 | RSS: Main: 252899328 10:35:12 INFO - PROCESS | 2910 | 10:35:14 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:35:15 INFO - PROCESS | 2910 | RSS: Main: 255119360 10:35:15 INFO - PROCESS | 2910 | 10:35:18 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:35:18 INFO - PROCESS | 2910 | RSS: Main: 260567040 10:35:18 INFO - PROCESS | 2910 | 10:35:21 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:35:22 INFO - PROCESS | 2910 | RSS: Main: 261050368 10:35:22 INFO - PROCESS | 2910 | 10:35:23 INFO - PROCESS | 2910 | Cycle 1(1): loaded http://localhost:61271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61271/tests/kraken/driver.html?testName=ai-astar) 10:35:24 INFO - PROCESS | 2910 | RSS: Main: 305582080 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | __start_tp_report 10:35:24 INFO - PROCESS | 2910 | _x_x_mozilla_page_load 10:35:24 INFO - PROCESS | 2910 | _x_x_mozilla_page_load_details 10:35:24 INFO - PROCESS | 2910 | |i|pagename|runs| 10:35:24 INFO - PROCESS | 2910 | |0;ai-astar;104;115;115;111;113;116;111;114;114;114 10:35:24 INFO - PROCESS | 2910 | |1;audio-beat-detection;134;106;112;170;138;131;107;110;143;106 10:35:24 INFO - PROCESS | 2910 | |2;audio-dft;185;185;184;206;173;249;264;194;184;187 10:35:24 INFO - PROCESS | 2910 | |3;audio-fft;83;60;58;91;61;59;57;68;74;59 10:35:24 INFO - PROCESS | 2910 | |4;audio-oscillator;87;71;86;71;68;69;82;68;68;83 10:35:24 INFO - PROCESS | 2910 | |5;imaging-gaussian-blur;87;87;87;87;88;86;87;86;88;87 10:35:24 INFO - PROCESS | 2910 | |6;imaging-darkroom;94;93;100;94;94;94;93;94;94;94 10:35:24 INFO - PROCESS | 2910 | |7;imaging-desaturate;85;86;86;87;84;83;86;93;83;99 10:35:24 INFO - PROCESS | 2910 | |8;json-parse-financial;57;60;58;60;63;64;64;63;63;64 10:35:24 INFO - PROCESS | 2910 | |9;json-stringify-tinderbox;56;68;79;92;89;81;58;58;58;58 10:35:24 INFO - PROCESS | 2910 | |10;stanford-crypto-aes;84;83;83;84;82;82;94;81;104;94 10:35:24 INFO - PROCESS | 2910 | |11;stanford-crypto-ccm;138;143;143;118;133;132;134;139;153;136 10:35:24 INFO - PROCESS | 2910 | |12;stanford-crypto-pbkdf2;161;162;162;161;162;159;159;159;178;159 10:35:24 INFO - PROCESS | 2910 | |13;stanford-crypto-sha256-iterative;65;61;63;62;63;57;55;77;74;81 10:35:24 INFO - PROCESS | 2910 | __end_tp_report 10:35:24 INFO - PROCESS | 2910 | __start_cc_report 10:35:24 INFO - PROCESS | 2910 | _x_x_mozilla_cycle_collect,2115 10:35:24 INFO - PROCESS | 2910 | __end_cc_report 10:35:24 INFO - PROCESS | 2910 | __startTimestamp1494869724129__endTimestamp 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | ------- Summary: start ------- 10:35:24 INFO - PROCESS | 2910 | Number of tests: 14 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#0] ai-astar Cycles:10 Average:112.70 Median:114.00 stddev:3.47 (3.0%) stddev-sans-first:1.73 10:35:24 INFO - PROCESS | 2910 | Values: 104.0 115.0 115.0 111.0 113.0 116.0 111.0 114.0 114.0 114.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:132.50 stddev:21.27 (16.0%) stddev-sans-first:22.34 10:35:24 INFO - PROCESS | 2910 | Values: 134.0 106.0 112.0 170.0 138.0 131.0 107.0 110.0 143.0 106.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#2] audio-dft Cycles:10 Average:201.10 Median:190.50 stddev:30.58 (16.1%) stddev-sans-first:31.88 10:35:24 INFO - PROCESS | 2910 | Values: 185.0 185.0 184.0 206.0 173.0 249.0 264.0 194.0 184.0 187.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#3] audio-fft Cycles:10 Average:67.00 Median:64.50 stddev:11.91 (18.5%) stddev-sans-first:11.13 10:35:24 INFO - PROCESS | 2910 | Values: 83.0 60.0 58.0 91.0 61.0 59.0 57.0 68.0 74.0 59.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.50 stddev:8.11 (10.6%) stddev-sans-first:7.42 10:35:24 INFO - PROCESS | 2910 | Values: 87.0 71.0 86.0 71.0 68.0 69.0 82.0 68.0 68.0 83.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 10:35:24 INFO - PROCESS | 2910 | Values: 87.0 87.0 87.0 87.0 88.0 86.0 87.0 86.0 88.0 87.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 10:35:24 INFO - PROCESS | 2910 | Values: 94.0 93.0 100.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#7] imaging-desaturate Cycles:10 Average:87.20 Median:86.00 stddev:5.03 (5.8%) stddev-sans-first:5.27 10:35:24 INFO - PROCESS | 2910 | Values: 85.0 86.0 86.0 87.0 84.0 83.0 86.0 93.0 83.0 99.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:63.00 stddev:2.63 (4.2%) stddev-sans-first:2.20 10:35:24 INFO - PROCESS | 2910 | Values: 57.0 60.0 58.0 60.0 63.0 64.0 64.0 63.0 63.0 64.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:73.50 stddev:14.23 (19.4%) stddev-sans-first:14.20 10:35:24 INFO - PROCESS | 2910 | Values: 56.0 68.0 79.0 92.0 89.0 81.0 58.0 58.0 58.0 58.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:83.50 stddev:7.62 (9.1%) stddev-sans-first:8.00 10:35:24 INFO - PROCESS | 2910 | Values: 84.0 83.0 83.0 84.0 82.0 82.0 94.0 81.0 104.0 94.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#11] stanford-crypto-ccm Cycles:10 Average:136.90 Median:138.50 stddev:9.10 (6.6%) stddev-sans-first:9.64 10:35:24 INFO - PROCESS | 2910 | Values: 138.0 143.0 143.0 118.0 133.0 132.0 134.0 139.0 153.0 136.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:161.50 stddev:5.71 (3.5%) stddev-sans-first:6.04 10:35:24 INFO - PROCESS | 2910 | Values: 161.0 162.0 162.0 161.0 162.0 159.0 159.0 159.0 178.0 159.0 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - PROCESS | 2910 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:64.00 stddev:8.64 (13.5%) stddev-sans-first:9.16 10:35:24 INFO - PROCESS | 2910 | Values: 65.0 61.0 63.0 62.0 63.0 57.0 55.0 77.0 74.0 81.0 10:35:24 INFO - PROCESS | 2910 | -------- Summary: end -------- 10:35:24 INFO - PROCESS | 2910 | 10:35:24 INFO - TEST-INFO | 2910: exit 0 10:35:24 INFO - TEST-OK | kraken | took 59051ms 10:35:24 INFO - SUITE-END | took 1178s 10:35:25 INFO - Completed test suite (00:19:38) 10:35:25 INFO - javascript benchmark 10:35:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7826.085220029335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2998.880044798208, 3560.679538841504, 3572.69632081273, 3581.48068530063, 3604.0629436346558, 6238.282597501288, 6270.65511396873, 6283.591566548177, 6284.811455656338, 6292.307846136921, 6227.532935029868, 6285.245770507534, 6288.402601752826, 6291.811245662626, 6303.527235457336, 5531.446855314466, 5586.692731899744, 5624.55003599711, 5656.830295091154, 5732.168835518868, 5515.7242137893145, 5518.255035570187, 5561.499465048145, 5705.74324155414, 5723.027085395472, 5622.662640241578, 5627.915581266264, 5641.943580564182, 5674.460926212003, 5720.256366672327, 5697.31632204134, 5705.258316418881, 5709.857253568653, 5713.914291285634, 5716.742746576372, 5400.567954563626, 5541.5289700375515, 5632.662040277596, 5643.068893632546, 5715.971420142914, 6132.754689812402, 6159.661218632973, 6175.0, 6180.289266734293, 6207.34822843604, 6556.311587283314, 6577.177852768404, 6581.04574836646, 6585.506087043491, 6589.538732288742, 6602.504812139109, 6656.56732312403, 6678.833029174261, 6703.1285932828905, 6730.865382692367, 6167.352427995085, 6195.473384762256, 6213.471854892295, 6243.906341404882, 6361.268454127789, 6728.764493242713, 6738.932610673928, 6744.6627668616375, 6745.831354216086, 6761.594304341756, 6334.5249106317215, 6368.458681012074, 6384.074309225136, 6385.840353991188, 6415.550911436155, 6325.493960483151, 6398.5521013528605, 6466.1270728452, 6591.077249185118, 6619.271880093186, 6346.048092786119, 6348.0, 6393.104965304861, 6419.711112999927, 6459.257185423737, 6551.410373066352, 6566.015097735378, 6578.210614726264, 6592.70332835014, 6596.538242323088, 5286.9471305287225, 5317.813876514303, 5326.014687282865, 5343.973280133652, 5367.919481207785, 4679.508651591602, 4688.109259240733, 4715.434147902273, 4766.45185803637, 4782.0914026334885, 5173.5343819055715, 6190.535709821781, 6197.752089916443, 6199.225096862892, 6200.689965501707, 6018.578699491081, 6402.103705481236, 6486.967565162144, 6499.772507962199, 6522.412982831568, 6281.842953926097, 6322.399372059647, 6325.0, 6343.841403964845, 6344.746210151635, 5818.592698511064, 5828.417158284137, 5844.12338149281, 5856.385079566669, 5858.765649373977, 6525.706343214471, 6620.602763834186, 6728.259891411843, 6894.626100657069, 6932.79201623942, 5423.457654234545, 6473.1261279728515, 6484.1894763154605, 6508.934910651023, 6515.478761698958, 6703.497237707289, 6740.325967403221, 6792.117024786746, 6899.413549848319, 6944.965275173592, 6353.332900045429, 6574.539782215199, 6607.867842643216, 6608.768693095719, 6611.570247933869, 6379.298277189413, 6393.680315984089, 6522.05430212607, 6702.262751097278, 6739.05653208561, 6294.622322660655, 6372.968135159294, 6377.521685873486, 6504.804855854142, 6534.0, 6333.714982825876, 6465.0, 6467.353264673495, 6503.089567460654, 6505.21937367519, 5266.55234305073, 6340.1440805490665, 6346.238451385863, 6352.428281454691, 6362.904556431565, 6252.718627661857, 6284.905726414016, 6365.61806291624, 6456.192975878015, 6486.124373209556, 6253.749850005949, 6344.492440604833, 6355.046742988588, 6360.141380913703, 6371.904421433775], "value": 6068.520048393086, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.1770052941765, 815.6818840652145, 828.5153185386548, 911.3483859040784, 915.6291701860748, 30062.69937300629, 30536.54195187074, 41280.793596032054, 41722.0, 41965.7901710491, 42930.0, 43241.783791081, 43284.56715432845, 43422.91442713934, 43463.78268108663, 8876.689315873946, 9145.844683584153, 9784.315097943147, 9793.510324483783, 9795.804083918318, 7958.283754462097, 8114.350851931831, 11845.7630847383, 12022.458989345456, 12329.753404931897, 12058.216215945935, 12079.577214797484, 12109.697257568587, 12116.151869369147, 12131.150819442642, 12046.096542759284, 12076.154669173162, 12096.697582560419, 12099.27404355737, 12106.273623582525, 11601.941990290079, 11644.126690498246, 11680.649580512598, 11682.299062056303, 11697.824532632018, 40298.194036119115, 40646.59353406487, 40651.59348406507, 40745.592544074476, 40767.0, 4052.959470405288, 4084.2852500812537, 4094.7952602369764, 4105.794710264505, 4114.938275925863, 28562.0, 29332.853335733183, 30601.846990765127, 30772.0, 30848.766049357997, 3109.967781413127, 3292.4732042873266, 3292.6378098409155, 3330.5004249362546, 3391.440412331987, 364932.0, 369749.0, 370607.0, 370748.0, 370767.1461642702, 43566.78216608897, 53670.0, 53858.19212711813, 53873.92252154935, 54020.729896351055, 55595.722021389636, 55723.72138139365, 55729.44270557324, 55750.16374754382, 55759.44240557543, 15013.69972600542, 15317.004394714531, 15331.463398780988, 15350.539483815503, 15358.539243822703, 11699.649010529698, 13183.14309569875, 13189.208647481182, 13192.670183245304, 13206.405711742995, 9844.458554779412, 10683.679489615324, 10736.463176841127, 11794.76410471803, 12039.39803009846, 5331.493508116722, 5353.892922141613, 5357.8392648220615, 5368.597355198298, 5397.919031214535, 962.2109869906749, 970.9708708738608, 973.5764942250144, 977.7311239409078, 1018.7402212435929, 29383.971560995262, 29622.13124922466, 29662.55506167409, 29768.702312977028, 30488.0, 846.8137009857821, 1104.8011357955645, 1123.5786580032488, 1157.4039369724599, 1168.976620467603, 13311.600651980263, 13348.93325533366, 14026.228557429235, 14278.42886284537, 14496.275186240646, 492417.0, 495713.0, 510765.0, 609240.0, 612333.8766612366, 50693.0, 50763.492365076614, 50851.47445576639, 50866.491335086175, 51074.0, 1277.9297138657464, 1309.9148555343872, 1429.9428022879176, 1466.8973171877867, 1477.8078849749463, 25679.358016049748, 27147.59278610783, 27473.86263068672, 27491.31271718223, 27510.724892751616, 44796.0, 44947.77526112348, 45004.0, 45039.54960450485, 45071.0, 474824.0, 476749.0, 477630.0, 477703.0, 479659.0, 23809.404764881016, 23953.640695389233, 28279.575806363333, 28953.85523072371, 29081.7091829079, 25546.744532554436, 30789.846050769604, 34424.139396514285, 36018.27963440769, 36088.8195559031, 27701.72298277072, 27804.86097569499, 27952.860235698692, 28287.86848526036, 29025.85487072551, 27622.171334859988, 28089.0, 28236.0, 28252.85873570619, 28668.42663146767, 32024.0, 32110.35779284448, 32614.51078233876, 32673.673263268014, 32736.83631581827, 27686.0, 27904.02335918233, 27989.0, 28058.877644893975, 28837.27906802347, 21893.890530547244, 22351.44121396978, 22811.771882281628, 22904.969276382937, 22976.655350170095], "value": 18734.563401865296, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.4566954298495, 426.60965216826605, 438.90563528841295, 447.23299541286684, 448.654037886341, 21577.352679419622, 21982.0, 22550.548989020208, 22793.0, 22794.65808012879, 18823.529411764714, 23574.764252357472, 24182.75817241827, 24436.266911992625, 24674.0130394784, 6698.196216454033, 6807.387335139824, 6863.450923926074, 7022.016917631536, 7077.539959902616, 6812.931870681304, 6947.652617369136, 6950.617716025617, 6963.05998690178, 7323.926760732405, 7700.0, 7787.182345853689, 7790.571518566477, 7791.181925897784, 7804.1415444301365, 7549.35830454412, 7645.885311720329, 7668.38652907766, 7727.343175830061, 7742.380609551279, 7281.599512026836, 7470.2903224193615, 7484.700611975514, 7502.924970750332, 7552.66013029415, 25953.870230648725, 26113.347166320804, 26156.73843261581, 26272.211833645022, 26765.732342676518, 2825.731555502224, 3056.816591004547, 3186.8725250989933, 3223.9677603223904, 3231.3698828728407, 13240.271785051818, 16032.839671603251, 17197.484075477754, 17216.31134754608, 17362.913185434118, 2120.586485635302, 2162.2648299578063, 2185.868847869117, 2233.8883055847305, 2274.431392151962, 153634.9999999989, 168063.15968420202, 177461.112694437, 180550.0, 181288.18711812844, 16338.836611633731, 19395.709064364044, 20731.689024664644, 20913.26803561868, 20962.161513539286, 32564.86022989184, 32945.176370590445, 33082.8345858274, 33084.3383132336, 33104.8413305538, 10272.589096436059, 10422.791544169075, 10532.842007369896, 10761.0, 10916.563337466412, 6908.6545672716165, 8171.387145964077, 8969.282457403522, 8980.236679882284, 9177.174054335143, 9441.763955901157, 9537.570809313598, 9595.568199431042, 9606.231501479864, 9651.420914745138, 3324.7838890472044, 4014.538328092307, 4021.481203946547, 4074.8794081627198, 4081.1837632473625, 640.2956747577628, 763.7975936376864, 774.3688893551737, 777.0985656638272, 796.8924195233569, 19553.0, 22402.439939001655, 22559.43601409945, 22559.661605075937, 22592.0, 891.4027601506876, 897.1448636725564, 913.1188403190953, 914.7484441778564, 923.3444254579324, 10765.03114719679, 11574.247673901169, 11603.36181510025, 11619.941900290445, 11634.36011019402, 658384.4161558324, 746818.0, 775523.0, 779540.0, 807658.0, 161248.58127128103, 162627.0, 166680.0, 167155.16422418054, 175390.0, 1222.49877550204, 1244.1788419642992, 1284.2487145020082, 1285.460106755165, 1288.0275392079109, 19026.7145992813, 19054.95197764136, 19057.71413428772, 19186.040697965323, 19346.903265483583, 62166.68916655388, 62732.37267627265, 63421.682891585246, 63846.7230655375, 65878.01182982163, 89867.0, 90746.54626726825, 91284.0, 92914.0, 93031.53484232536, 11381.829272560753, 11397.316161030365, 11700.648980530597, 11915.582954596548, 11919.880801191877, 12794.616161515169, 12890.162139460905, 12977.675558111123, 12979.89670877986, 13020.739585208432, 10889.0199882011, 11021.779564408827, 11204.66386008421, 11229.4385280734, 11366.43167841588, 10373.740656483647, 10464.790704186025, 10497.52761125736, 10805.405702686428, 10824.188185885932, 11471.541138354372, 11644.41777911118, 11662.0, 11702.765944680888, 11811.763764724485, 11449.771004580029, 11489.138314626269, 11676.94161529221, 11698.76602467963, 11701.0, 9624.711258662252, 11316.98147166759, 11379.601713939972, 11414.942925285653, 11418.942905285421], "value": 11829.346749794884, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1615.6284054667433, 1619.4251040880488, 1628.096406494396, 1646.119326160504, 1660.6180578466951, 10971.561137554498, 11644.941775291132, 11653.126015548825, 11657.650270491888, 11672.299662020274, 10976.506057227423, 11325.603603873866, 11326.716832079203, 11537.480813363398, 11553.075753939685, 2401.86789726565, 2449.1917667169882, 2450.3092865922545, 2453.50929814037, 2461.2985299189736, 2350.4006478348083, 2423.1761201191593, 2424.75752424757, 2429.4898071404937, 2433.07543133609, 1972.2801177570154, 2415.10641062807, 2420.891059902309, 2422.842515236513, 2427.526632306701, 1958.9412317630495, 2326.0579465316405, 2396.8322217444697, 2403.51929614077, 2410.7830295273334, 1959.5590992026823, 2112.1973650012897, 2253.523941818112, 2326.9883650581637, 2399.9520009599887, 11424.942875285571, 11516.654500365003, 11520.884791152148, 11538.94230528842, 11540.192186546945, 5629.6340737852315, 5653.321601407816, 5866.560007999375, 5869.941300587025, 5937.614055086406, 3316.7844090134304, 3487.1630808606005, 3520.8591656333715, 3533.5583052118486, 3557.2707594942976, 1218.2507757729022, 1262.8421447319085, 1292.1923797626484, 1295.6307452376122, 1312.376621104977, 13897.79153312701, 14116.647083822883, 14558.708825823423, 15699.215039248105, 15933.203339833079, 3697.0757310672334, 4291.442112525352, 4541.9545804542195, 4679.181143299909, 4691.202495575761, 12260.754784904251, 12282.447289871798, 12288.815667764991, 12289.754204916031, 12293.877061229274, 4906.8773280668265, 5117.078925793318, 5157.355330583677, 5158.793648254103, 5719.5996280260815, 4507.549245075532, 5158.277841102249, 5321.627486076015, 5336.092864213093, 5337.8131765388025, 3824.330742120118, 3924.843006279717, 3965.960340396559, 3970.0, 3974.7615143091507, 853.9931161769915, 1008.3748076192661, 1018.3686114608989, 1030.3766221436074, 1037.4605205293205, 809.6073404398862, 825.8141918068386, 838.4678271619674, 859.0421679826948, 895.2569367425021, 6438.871222575523, 6741.764038258638, 6873.931260687429, 6892.896606550906, 6986.37122658953, 1034.425893629028, 1040.6929955663097, 1054.6097943760703, 1063.3938654966594, 1073.8067147913293, 3644.526211592529, 3650.7444478886755, 4220.704550681423, 4277.230098582223, 4309.612134907873, 11552.59565915189, 13589.0, 14109.85890141106, 14204.502842400467, 14216.218108003952, 13609.795853062213, 13768.862311376959, 14066.71866562663, 14132.858671413154, 14160.150390976574, 9973.950130249303, 10337.276390652582, 10534.736631584272, 10548.103411209979, 10584.57661693539, 7003.929960700328, 7008.369246767816, 7137.750178743719, 7315.926840731525, 7320.9267907320245, 12149.453274602842, 12575.811362829381, 12641.0, 12656.114072015238, 12787.232766034067, 11548.01841843419, 11594.88405115938, 12986.545470908473, 13015.739685206054, 13114.344282785632, 9144.3598948073, 9268.490233037248, 9526.0, 9634.807303854024, 9651.9034809651, 10419.635312764018, 10515.894841051491, 10583.735406614896, 10634.946825265824, 10642.521086550969, 9059.003509613805, 9109.453432794053, 9112.453252804853, 9138.908610913806, 9265.397749146285, 8876.334274929277, 8893.244074253502, 9037.277017838425, 9067.90932090697, 9084.273258139465, 8165.591720413837, 8178.386621003568, 8193.631286591999, 8275.420720549744, 8283.17168283167, 6029.095635654687, 6059.545534084873, 6067.787627433018, 6097.237845269341, 6170.6914654268, 6094.75620975156, 6099.8780024398375, 6166.229221347332, 6231.096491008688, 6253.187085678833], "value": 5173.66931465179, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.90326853578193, 373.09338307911764, 396.3954968672775, 399.58443219052185, 401.17558417452125, 4946.727929963855, 5065.620078494114, 5253.4221235664045, 5399.246384343984, 5417.59368047397, 4825.927611085836, 5153.60852569806, 5200.765965531551, 5201.947980520194, 5250.00999240577, 3924.9803750981346, 4010.6189911958318, 4126.968257935516, 4245.9575404246025, 4391.913840925567, 3555.6266592007855, 3946.815955213439, 4049.484824420764, 4221.409002739619, 4371.803268852909, 3845.2117315950163, 4032.354823228284, 4077.245709543744, 4399.5160532341415, 4419.801108950089, 4120.423140760296, 4237.809298581571, 4317.373980772802, 4331.913361732779, 4372.103718737651, 3989.92272086535, 3995.840166393341, 4006.759594424315, 4007.0966291011828, 4010.7994600270163, 4710.481846996862, 5015.84952451427, 5115.411727651293, 5153.433122356538, 5178.275041494194, 4152.712659075696, 4356.8257269709175, 4433.556644335573, 4824.396950381202, 4898.848770538921, 4844.515548445127, 4954.801807927714, 5383.896301258233, 5565.749541270612, 5566.860828479321, 3826.617338266179, 4017.658499027586, 4062.923325318793, 4484.618807401343, 4522.773861306921, 5274.683518988873, 5476.795105076877, 5555.194691636242, 5692.747595528977, 6000.669963152025, 3787.4318852172396, 3997.780122093313, 4037.707933461264, 4155.709100363006, 4352.434183556117, 4229.281022226168, 4330.376108959141, 4390.385346051556, 4565.520620334817, 4591.724496530219, 4223.303154979455, 4580.335851301543, 4956.677815941952, 4985.376827896513, 5007.774650140753, 4675.462321832964, 4942.332785074041, 5255.921161182585, 5568.0, 5582.804601838916, 1354.3296068446182, 1492.843251458603, 1524.8703860171788, 1572.716910956016, 1610.9758353624704, 1035.77213013137, 1108.9465008242203, 1131.9151063670258, 1196.629044996054, 1216.367488905764, 4452.510223875371, 4939.678920870204, 5261.631685781958, 5268.683878967274, 5300.787968481295, 5445.401005889349, 5759.848030393938, 5796.797217057491, 5800.709964501758, 5907.911381329283, 4586.954130458719, 5286.36563612369, 5297.496737809902, 5332.440093790174, 5334.946650533522, 2870.971290287112, 2875.8274503529856, 2902.201894478993, 3002.8198308101582, 3004.414139242827, 5265.394479634814, 5421.918671219856, 5899.616524925866, 5924.7333869975955, 6012.414661695513, 5062.9622226663705, 5656.8020119295625, 5755.683437410982, 5799.681017543991, 5906.2912450505355, 4576.057857513811, 5753.769849206069, 5807.535397168216, 5883.999720047602, 6016.368281330485, 5458.8908221834545, 5599.664020158803, 5760.724513816935, 5778.91958894806, 5780.046292361713, 5254.00173966945, 5450.945490545202, 5486.31421072366, 5544.778208871761, 5704.458076482728, 5973.193618861572, 5983.700814959322, 5990.981533139289, 6000.369961154191, 6015.841683116399, 4405.581469760367, 4569.383133277098, 4571.657125715518, 4593.747343896118, 4609.677322587387, 5217.8173763918085, 5622.775088996558, 5733.308673941114, 5742.502011624956, 5755.5683323750045, 5234.691327168208, 5340.973295133655, 5396.679427846064, 5562.582806289463, 5597.720113994365, 5149.665271757473, 5352.805349806561, 5478.91781623284, 5496.7526461310135, 5609.607327487037, 5457.672539647634, 5470.0, 5480.890382192254, 5497.45025497447, 5531.032069387921, 5021.949780502148, 5253.213907271546, 5326.14781634952, 5605.355384130714, 5615.298087739033, 4798.352222449925, 4821.566059054702, 4915.459299476983, 5270.156774916149, 5380.892382152413], "value": 4252.289612263412, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.9191588925214, 756.1228974389709, 780.8087018680424, 866.402182494079, 885.5882014863088, 16282.0, 17110.486685399443, 17132.571685707862, 17164.656706865888, 17180.742288865677, 15724.685506289867, 15905.284262208199, 15923.442679506219, 15940.920295398535, 15950.28223729932, 977.5454413697629, 983.6311383231289, 990.7176454710409, 1008.3949630221881, 1023.0587859169544, 1003.9799204015916, 1014.9391036537794, 1023.2581378500602, 1023.8105950399163, 1026.2405819693447, 1063.3035361838008, 1065.2809353686268, 1072.9731756706067, 1073.2809017957986, 1077.0091515805477, 1062.984055239172, 1063.9627613033583, 1064.5316060933164, 1066.594694016269, 1067.685032915292, 798.660569258063, 799.5402643479964, 799.8480288745178, 800.5476905548405, 804.252045597594, 16458.0, 16543.586410339718, 17048.82951170497, 17892.194851231725, 18144.727829082574, 14439.35022923971, 14509.782353264709, 14593.48922787708, 14603.123812571173, 14619.561413157622, 10457.163426925828, 11753.4710938008, 11811.822822657581, 11837.822432663517, 11943.761124777542, 3470.774399664014, 3586.3053913595472, 3795.6773674237447, 3797.35444974355, 3817.1793064491267, 110499.44750276198, 113835.43082284536, 118843.24627014909, 118890.40554797256, 120382.398088009, 11007.504662290215, 11129.888701113046, 11258.549658013751, 11348.659540213806, 11500.079993600491, 22770.43073923165, 23191.0, 23226.53546929052, 23240.535189296457, 23241.3027609172, 7828.882566761503, 7829.725959591501, 7867.370610351159, 7998.080220774569, 8134.633941472648, 4462.0, 4507.526709695435, 4568.520305367889, 4637.791299391535, 4654.2785868190485, 2918.5184444566835, 3397.405454045532, 3578.8210589470423, 3581.122624956902, 3583.964160358415, 625.6590158363703, 626.9185005949289, 635.7806556737918, 638.3712043637014, 645.9547831651739, 670.9563878347892, 672.8015235505537, 690.547691262224, 701.8631366883409, 730.3098571849551, 9929.3545919515, 10348.586056557804, 10370.637027704144, 10502.947485262677, 10707.0, 684.2507454337492, 692.1071817355655, 700.4291502425507, 702.9578225306498, 707.3987110955646, 4969.2546118081855, 5083.729067733067, 5089.262057001714, 5090.211017292326, 5094.133997220481, 107457.0, 109724.0, 112695.0, 112867.0, 115321.42339288417, 92952.0, 94605.52697236469, 94607.0539294612, 95628.0, 96482.0, 78438.6078069606, 79234.0, 80531.59734201408, 81641.9999999988, 81650.0, 9739.071635973258, 9825.213982881209, 9828.606855725977, 9862.654807081717, 10388.902650483033, 55358.0, 55984.0, 56641.71679141743, 56650.43349566452, 57143.0, 68919.65540172267, 68944.0, 69112.65443672749, 70158.0, 70725.64637176781, 9172.266218702354, 11414.771704566028, 11677.357745324089, 11748.236364636272, 11773.941130294294, 12762.234265944073, 12972.28652424088, 13104.541341053018, 13262.288900795686, 13782.966277529425, 11741.178117532032, 12294.815577766161, 12446.066545009342, 12457.501699931901, 12750.553730619384, 10370.377777333384, 11591.0, 11760.76478470443, 12281.38593070325, 12414.565490207799, 11794.410279485819, 12322.0, 12416.565420210249, 12611.621651350475, 12711.80932285998, 9135.817283654422, 9348.532573371169, 9412.711582315009, 9460.763480913032, 9584.9041509584, 10307.381557106597, 10381.896181038093, 10386.0, 10391.532381043022, 10400.947995260278], "value": 7764.586032314573, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1433.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 111.0, 113.0, 116.0, 111.0, 114.0, 114.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 106.0, 112.0, 170.0, 138.0, 131.0, 107.0, 110.0, 143.0, 106.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 206.0, 173.0, 249.0, 264.0, 194.0, 184.0, 187.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 58.0, 91.0, 61.0, 59.0, 57.0, 68.0, 74.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 86.0, 71.0, 68.0, 69.0, 82.0, 68.0, 68.0, 83.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 87.0, 88.0, 86.0, 87.0, 86.0, 88.0, 87.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 100.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 87.0, 84.0, 83.0, 86.0, 93.0, 83.0, 99.0], "value": 87.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 58.0, 60.0, 63.0, 64.0, 64.0, 63.0, 63.0, 64.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 68.0, 79.0, 92.0, 89.0, 81.0, 58.0, 58.0, 58.0, 58.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 82.0, 82.0, 94.0, 81.0, 104.0, 94.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 143.0, 118.0, 133.0, 132.0, 134.0, 139.0, 153.0, 136.0], "value": 136.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 162.0, 159.0, 159.0, 159.0, 178.0, 159.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 63.0, 62.0, 63.0, 57.0, 55.0, 77.0, 74.0, 81.0], "value": 65.8, "unit": "score"}]}]} 10:35:25 INFO - Return code: 0 10:35:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:35:25 INFO - # TBPL SUCCESS # 10:35:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:35:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:35:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:35:25 INFO - 2017-05-15 10:35:25.407 screenresolution[2914:51087] starting screenresolution argv=screenresolution get 10:35:25 INFO - 2017-05-15 10:35:25.410 screenresolution[2914:51087] Display 0: 1600x1200x32@60 10:35:25 INFO - 2017-05-15 10:35:25.416 screenresolution[2915:51089] starting screenresolution argv=screenresolution list 10:35:25 INFO - Available Modes on Display 0 10:35:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:35:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:35:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:35:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:35:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:35:25 INFO - Intel Iris: 10:35:25 INFO - Chipset Model: Intel Iris 10:35:25 INFO - Type: GPU 10:35:25 INFO - Bus: Built-In 10:35:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:35:25 INFO - Vendor: Intel (0x8086) 10:35:25 INFO - Device ID: 0x0a2e 10:35:25 INFO - Revision ID: 0x0009 10:35:25 INFO - Displays: 10:35:25 INFO - Display: 10:35:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:35:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:35:25 INFO - Display Serial Number: 1600x1200 60 10:35:25 INFO - Main Display: Yes 10:35:25 INFO - Mirror: Off 10:35:25 INFO - Online: Yes 10:35:25 INFO - Rotation: Supported 10:35:25 INFO - Return code: 0 10:35:25 INFO - Running post-action listener: _resource_record_post_action 10:35:25 INFO - [mozharness: 2017-05-15 17:35:25.651249Z] Finished run-tests step (success) 10:35:25 INFO - Running post-run listener: _resource_record_post_run 10:35:25 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 1523712; Write bytes: 424353792; Read time: 11; Write time: 1422 10:35:25 INFO - TinderboxPrint: CPU usage
53.8% 10:35:25 INFO - TinderboxPrint: I/O read bytes / time
1,523,712 / 11 10:35:25 INFO - TinderboxPrint: I/O write bytes / time
424,353,792 / 1,422 10:35:25 INFO - TinderboxPrint: CPU idle
2,207.0 (46.2%) 10:35:25 INFO - TinderboxPrint: CPU system
957.3 (20.0%) 10:35:25 INFO - TinderboxPrint: CPU user
1,613.3 (33.8%) 10:35:25 INFO - TinderboxPrint: Swap in / out
122,990,592 / 0 10:35:25 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122845696; Write bytes: 122834944; Read time: 11180; Write time: 278 10:35:25 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 589824; Write bytes: 300208128; Read time: 6; Write time: 1135 10:35:26 INFO - Running post-run listener: _upload_blobber_files 10:35:26 INFO - Blob upload gear active. 10:35:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:35:26 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 10:35:26 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'] 10:35:26 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 10:35:26 INFO - (blobuploader) - INFO - Open directory for files ... 10:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:35:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:27 INFO - (blobuploader) - INFO - Done attempting. 10:35:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:35:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:35:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:28 INFO - (blobuploader) - INFO - Done attempting. 10:35:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:35:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:35:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:29 INFO - (blobuploader) - INFO - Done attempting. 10:35:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:35:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:31 INFO - (blobuploader) - INFO - Done attempting. 10:35:31 INFO - (blobuploader) - INFO - Iteration through files over. 10:35:31 INFO - Return code: 0 10:35:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:35:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:35:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/deea059d8306816b87077dee55cabadc4cdeffeb54e5bc9ad6bd8dabd5f63fd5e4ba6d503f9a9e4bae4d0beb1f69d749d08d74653c77f8bbed37b8b1efc0ea9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/745d1bf1dfc0fa01e21c0ef32f7479751a92215339ab04180c6298755fd97a9d8f03a80e67a46cfc359291a2feade9e3f60fea6b10ee9c6b229e632da40fb143", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c4a032bd0b34d80f129a201d2c8c0eab44b021b5b862a33c78de85be8e0f7a79f1e11cfdc8e6ca57630654e2b7e8bdb340b7739a173714d07ec3e259483ac95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c0607ce91c070d3db774f90eff72097c362307f1da7566ea2aeab0acd2ec41fed6d1e14161441a16f3fabd60113e28ad265bdd04facb250dd62f10fdce26dd"} 10:35:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:35:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:35:31 INFO - Contents: 10:35:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/deea059d8306816b87077dee55cabadc4cdeffeb54e5bc9ad6bd8dabd5f63fd5e4ba6d503f9a9e4bae4d0beb1f69d749d08d74653c77f8bbed37b8b1efc0ea9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/745d1bf1dfc0fa01e21c0ef32f7479751a92215339ab04180c6298755fd97a9d8f03a80e67a46cfc359291a2feade9e3f60fea6b10ee9c6b229e632da40fb143", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c4a032bd0b34d80f129a201d2c8c0eab44b021b5b862a33c78de85be8e0f7a79f1e11cfdc8e6ca57630654e2b7e8bdb340b7739a173714d07ec3e259483ac95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c0607ce91c070d3db774f90eff72097c362307f1da7566ea2aeab0acd2ec41fed6d1e14161441a16f3fabd60113e28ad265bdd04facb250dd62f10fdce26dd"} 10:35:31 INFO - Running post-run listener: copy_logs_to_upload_dir 10:35:31 INFO - Copying logs to upload dir... 10:35:31 INFO - mkdir: /builds/slave/test/build/upload/logs 10:35:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.053761 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 10:35:31.859774) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.863431) ========= 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.ypk8HSSfxj/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.iYZraaYXmg/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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/deea059d8306816b87077dee55cabadc4cdeffeb54e5bc9ad6bd8dabd5f63fd5e4ba6d503f9a9e4bae4d0beb1f69d749d08d74653c77f8bbed37b8b1efc0ea9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/745d1bf1dfc0fa01e21c0ef32f7479751a92215339ab04180c6298755fd97a9d8f03a80e67a46cfc359291a2feade9e3f60fea6b10ee9c6b229e632da40fb143", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c4a032bd0b34d80f129a201d2c8c0eab44b021b5b862a33c78de85be8e0f7a79f1e11cfdc8e6ca57630654e2b7e8bdb340b7739a173714d07ec3e259483ac95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c0607ce91c070d3db774f90eff72097c362307f1da7566ea2aeab0acd2ec41fed6d1e14161441a16f3fabd60113e28ad265bdd04facb250dd62f10fdce26dd"} build_url:https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/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/deea059d8306816b87077dee55cabadc4cdeffeb54e5bc9ad6bd8dabd5f63fd5e4ba6d503f9a9e4bae4d0beb1f69d749d08d74653c77f8bbed37b8b1efc0ea9e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/745d1bf1dfc0fa01e21c0ef32f7479751a92215339ab04180c6298755fd97a9d8f03a80e67a46cfc359291a2feade9e3f60fea6b10ee9c6b229e632da40fb143", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c4a032bd0b34d80f129a201d2c8c0eab44b021b5b862a33c78de85be8e0f7a79f1e11cfdc8e6ca57630654e2b7e8bdb340b7739a173714d07ec3e259483ac95", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/29c0607ce91c070d3db774f90eff72097c362307f1da7566ea2aeab0acd2ec41fed6d1e14161441a16f3fabd60113e28ad265bdd04facb250dd62f10fdce26dd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.896703) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.896988) ========= 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.ypk8HSSfxj/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.iYZraaYXmg/Listeners TMPDIR=/var/folders/vd/gwz783gs6snbfbbmm1cw7rnw00000w/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.005349 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.945605) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 10:35:31.945888) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 10:35:31.946191) ========= ========= Total master_lag: 2.57 =========