builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0353 starttime: 1496156664.82 results: success (0) buildid: 20170530071016 builduid: 4e3ce2e1f8244f70a1c4180497b38b11 revision: 3524778fe631486d5a9cd4f5392177f297b337dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.825589) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.826036) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.826320) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.024866) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.025176) ========= 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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-30 08:04:25-- 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% 17.5M=0.001s 2017-05-30 08:04:25 (17.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.600213 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.655902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.656236) ========= 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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.027352 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.704348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.704668) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3524778fe631486d5a9cd4f5392177f297b337dc --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 3524778fe631486d5a9cd4f5392177f297b337dc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-30 08:04:25,801 truncating revision to first 12 chars 2017-05-30 08:04:25,801 Setting DEBUG logging. 2017-05-30 08:04:25,802 attempt 1/10 2017-05-30 08:04:25,802 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3524778fe631?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 08:04:26,455 unpacking tar archive at: mozilla-beta-3524778fe631/testing/mozharness/ program finished with exit code 0 elapsedTime=0.949322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.675911) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.676221) ========= script_repo_revision: 3524778fe631486d5a9cd4f5392177f297b337dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.676576) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.676838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.696312) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-30 08:04:26.696580) ========= /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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:04:26 INFO - MultiFileLogger online at 20170530 08:04:26 in /builds/slave/test 08:04:26 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 08:04:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:04:26 INFO - {'append_to_log': False, 08:04:26 INFO - 'base_work_dir': '/builds/slave/test', 08:04:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:04:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:04:26 INFO - 'branch': 'Mozilla-Beta', 08:04:26 INFO - 'buildbot_json_path': 'buildprops.json', 08:04:26 INFO - 'config_files': ('talos/mac_config.py',), 08:04:26 INFO - 'default_actions': ('clobber', 08:04:26 INFO - 'read-buildbot-config', 08:04:26 INFO - 'download-and-extract', 08:04:26 INFO - 'populate-webroot', 08:04:26 INFO - 'create-virtualenv', 08:04:26 INFO - 'install', 08:04:26 INFO - 'run-tests'), 08:04:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:04:26 INFO - 'download_minidump_stackwalk': True, 08:04:26 INFO - 'download_symbols': 'ondemand', 08:04:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:04:26 INFO - 'tooltool.py': '/tools/tooltool.py', 08:04:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:04:26 INFO - '/tools/misc-python/virtualenv.py')}, 08:04:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:04:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:04:26 INFO - 'gecko_profile': False, 08:04:26 INFO - 'gecko_profile_interval': 0, 08:04:26 INFO - 'installer_path': 'installer.exe', 08:04:26 INFO - 'log_level': 'info', 08:04:26 INFO - 'log_name': 'talos', 08:04:26 INFO - 'log_to_console': True, 08:04:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:04:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:04:26 INFO - 'opt_config_files': (), 08:04:26 INFO - 'pip_index': False, 08:04:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:26 INFO - 'cmd': ('bash', 08:04:26 INFO - '-c', 08:04:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:26 INFO - 'enabled': True, 08:04:26 INFO - 'halt_on_failure': False, 08:04:26 INFO - 'name': 'check_screen_resolution'},), 08:04:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:26 INFO - 'cmd': ('bash', 08:04:26 INFO - '-c', 08:04:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:26 INFO - 'enabled': True, 08:04:26 INFO - 'halt_on_failure': False, 08:04:26 INFO - 'name': 'check_screen_resolution'},), 08:04:26 INFO - 'run_cmd_checks_enabled': True, 08:04:26 INFO - 'suite': 'dromaeojs', 08:04:26 INFO - 'system_bits': '32', 08:04:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:04:26 INFO - 'title': 't-yosemite-r7-0353', 08:04:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:04:26 INFO - 'use_talos_json': True, 08:04:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:04:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:04:26 INFO - 'work_dir': 'build'} 08:04:26 INFO - [mozharness: 2017-05-30 15:04:26.899360Z] Running clobber step. 08:04:26 INFO - Running pre-action listener: _resource_record_pre_action 08:04:26 INFO - Running main action method: clobber 08:04:26 INFO - rmtree: /builds/slave/test/build 08:04:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:04:29 INFO - Running post-action listener: _resource_record_post_action 08:04:29 INFO - [mozharness: 2017-05-30 15:04:29.311817Z] Finished clobber step (success) 08:04:29 INFO - [mozharness: 2017-05-30 15:04:29.311944Z] Running read-buildbot-config step. 08:04:29 INFO - Running pre-action listener: _resource_record_pre_action 08:04:29 INFO - Running main action method: read_buildbot_config 08:04:29 INFO - Using buildbot properties: 08:04:29 INFO - { 08:04:29 INFO - "project": "", 08:04:29 INFO - "product": "firefox", 08:04:29 INFO - "script_repo_revision": "production", 08:04:29 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 08:04:29 INFO - "repository": "", 08:04:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 08:04:29 INFO - "buildid": "20170530071016", 08:04:29 INFO - "pgo_build": "False", 08:04:29 INFO - "basedir": "/builds/slave/test", 08:04:29 INFO - "buildnumber": 6, 08:04:29 INFO - "slavename": "t-yosemite-r7-0353", 08:04:29 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:04:29 INFO - "platform": "yosemite_r7_devedition", 08:04:29 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 08:04:29 INFO - "branch": "mozilla-beta", 08:04:29 INFO - "repo_path": "releases/mozilla-beta", 08:04:29 INFO - "revision": "3524778fe631486d5a9cd4f5392177f297b337dc", 08:04:29 INFO - "stage_platform": "macosx64-devedition", 08:04:29 INFO - "builduid": "4e3ce2e1f8244f70a1c4180497b38b11", 08:04:29 INFO - "slavebuilddir": "test" 08:04:29 INFO - } 08:04:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:04:29 INFO - Running post-action listener: _resource_record_post_action 08:04:29 INFO - [mozharness: 2017-05-30 15:04:29.315310Z] Finished read-buildbot-config step (success) 08:04:29 INFO - [mozharness: 2017-05-30 15:04:29.315416Z] Running download-and-extract step. 08:04:29 INFO - Running pre-action listener: _resource_record_pre_action 08:04:29 INFO - Running main action method: download_and_extract 08:04:29 INFO - mkdir: /builds/slave/test/build/tests 08:04:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:29 INFO - trying https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:04:29 INFO - Downloading https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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 08:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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 08:04:30 INFO - Downloaded 1287 bytes. 08:04:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:04:30 INFO - Using the following test package requirements: 08:04:30 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 08:04:30 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:04:30 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:04:30 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:04:30 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:04:30 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:04:30 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:04:30 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:04:30 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:04:30 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:04:30 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:30 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:04:30 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:04:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:04:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:04:30 INFO - Fetch https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:04:31 INFO - Expected file size: 51964297 08:04:31 INFO - Obtained file size: 51964297 08:04:47 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 08:04:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:04:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:04:47 INFO - Fetch https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:04:50 INFO - Expected file size: 51964297 08:04:50 INFO - Obtained file size: 51964297 08:05:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:05:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:05:05 INFO - Fetch https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:05:06 INFO - Expected file size: 14404461 08:05:06 INFO - Obtained file size: 14404461 08:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:06 INFO - trying https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:05:06 INFO - Downloading https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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 08:05:08 INFO - Downloaded 74893626 bytes. 08:05:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:05:08 INFO - mkdir: /builds/slave/test/properties 08:05:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:05:08 INFO - Writing to file /builds/slave/test/properties/build_url 08:05:08 INFO - Contents: 08:05:08 INFO - build_url:https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:05:09 INFO - Running post-action listener: _resource_record_post_action 08:05:09 INFO - Running post-action listener: set_extra_try_arguments 08:05:09 INFO - [mozharness: 2017-05-30 15:05:09.419047Z] Finished download-and-extract step (success) 08:05:09 INFO - [mozharness: 2017-05-30 15:05:09.419207Z] Running populate-webroot step. 08:05:09 INFO - Running pre-action listener: _resource_record_pre_action 08:05:09 INFO - Running main action method: populate_webroot 08:05:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:05:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:05:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:05:09 INFO - '--tppagecycles', 08:05:09 INFO - '7'], 08:05:09 INFO - 'tests': ['tsvgm']}}, 08:05:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:05:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:05:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:05:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:05:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['damp', 'tps']}, 08:05:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'tests': ['damp', 'tps']}, 08:05:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['dromaeo_dom']}, 08:05:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:05:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['basic_compositor_video']}, 08:05:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:05:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['a11yr', 08:05:09 INFO - 'ts_paint', 08:05:09 INFO - 'tpaint', 08:05:09 INFO - 'sessionrestore', 08:05:09 INFO - 'sessionrestore_no_auto_restore', 08:05:09 INFO - 'tabpaint']}, 08:05:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:05:09 INFO - 'ts_paint', 08:05:09 INFO - 'tpaint', 08:05:09 INFO - 'sessionrestore', 08:05:09 INFO - 'sessionrestore_no_auto_restore', 08:05:09 INFO - 'tabpaint']}, 08:05:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:05:09 INFO - 'ts_paint', 08:05:09 INFO - 'tpaint', 08:05:09 INFO - 'sessionrestore', 08:05:09 INFO - 'sessionrestore_no_auto_restore', 08:05:09 INFO - 'tabpaint']}, 08:05:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:05:09 INFO - 'ts_paint', 08:05:09 INFO - 'tpaint', 08:05:09 INFO - 'sessionrestore', 08:05:09 INFO - 'sessionrestore_no_auto_restore', 08:05:09 INFO - 'tabpaint']}, 08:05:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['a11yr', 08:05:09 INFO - 'ts_paint', 08:05:09 INFO - 'tpaint', 08:05:09 INFO - 'sessionrestore', 08:05:09 INFO - 'sessionrestore_no_auto_restore', 08:05:09 INFO - 'tabpaint']}, 08:05:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['a11yr', 08:05:09 INFO - 'ts_paint', 08:05:09 INFO - 'tpaint', 08:05:09 INFO - 'sessionrestore', 08:05:09 INFO - 'sessionrestore_no_auto_restore', 08:05:09 INFO - 'tabpaint']}, 08:05:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['tsvgx', 08:05:09 INFO - 'tsvgr_opacity', 08:05:09 INFO - 'tart', 08:05:09 INFO - 'tscrollx', 08:05:09 INFO - 'cart', 08:05:09 INFO - 'tsvg_static']}, 08:05:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:05:09 INFO - 'tsvgr_opacity', 08:05:09 INFO - 'tart', 08:05:09 INFO - 'tscrollx', 08:05:09 INFO - 'cart', 08:05:09 INFO - 'tsvg_static']}, 08:05:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'talos_options': ['--disable-e10s'], 08:05:09 INFO - 'tests': ['tp5o']}, 08:05:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:05:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'talos_options': ['--disable-e10s', 08:05:09 INFO - '--xperf_path', 08:05:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:09 INFO - 'tests': ['tp5n']}, 08:05:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:05:09 INFO - 'talos_options': ['--xperf_path', 08:05:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:09 INFO - 'tests': ['tp5n']}}, 08:05:09 INFO - 'talos.zip': {'path': '', 08:05:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:05:09 INFO - Running post-action listener: _resource_record_post_action 08:05:09 INFO - [mozharness: 2017-05-30 15:05:09.437713Z] Finished populate-webroot step (success) 08:05:09 INFO - [mozharness: 2017-05-30 15:05:09.437825Z] Running create-virtualenv step. 08:05:09 INFO - Running pre-action listener: _resource_record_pre_action 08:05:09 INFO - Running main action method: create_virtualenv 08:05:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:05:09 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 08:05:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:05:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:05:09 INFO - Using real prefix '/tools/python27' 08:05:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:05:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:05:13 INFO - Installing pip.................done. 08:05:13 INFO - Return code: 0 08:05:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:05:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:05:13 INFO - Reading from file tmpfile_stdout 08:05:13 INFO - Output received: 08:05:13 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) 08:05:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:05:13 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')]} 08:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:13 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')]} 08:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:13 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:13 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 08:05:13 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 08:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:13 INFO - 'HOME': '/Users/cltbld', 08:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:13 INFO - 'LOGNAME': 'cltbld', 08:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:13 INFO - 'MOZ_NO_REMOTE': '1', 08:05:13 INFO - 'NO_EM_RESTART': '1', 08:05:13 INFO - 'PAGER': '/bin/cat', 08:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:13 INFO - 'PWD': '/builds/slave/test', 08:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:13 INFO - 'SHELL': '/bin/bash', 08:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:13 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:13 INFO - 'USER': 'cltbld', 08:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:13 INFO - 'XPC_FLAGS': '0x0', 08:05:13 INFO - 'XPC_SERVICE_NAME': '0', 08:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:13 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 08:05:13 INFO - Cleaning up... 08:05:13 INFO - Return code: 0 08:05:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:05:13 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')]} 08:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:13 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')]} 08:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:13 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:13 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 08:05:13 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 08:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:13 INFO - 'HOME': '/Users/cltbld', 08:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:13 INFO - 'LOGNAME': 'cltbld', 08:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:13 INFO - 'MOZ_NO_REMOTE': '1', 08:05:13 INFO - 'NO_EM_RESTART': '1', 08:05:13 INFO - 'PAGER': '/bin/cat', 08:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:13 INFO - 'PWD': '/builds/slave/test', 08:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:13 INFO - 'SHELL': '/bin/bash', 08:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:13 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:13 INFO - 'USER': 'cltbld', 08:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:13 INFO - 'XPC_FLAGS': '0x0', 08:05:13 INFO - 'XPC_SERVICE_NAME': '0', 08:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:13 INFO - Downloading/unpacking psutil>=3.1.1 08:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:05:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:16 INFO - Installing collected packages: psutil 08:05:16 INFO - Running setup.py install for psutil 08:05:16 INFO - building 'psutil._psutil_osx' extension 08:05:16 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 08:05:16 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 08:05:16 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 08:05:17 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 08:05:17 INFO - building 'psutil._psutil_posix' extension 08:05:17 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 08:05:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:05:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:05:17 INFO - ^ 08:05:17 INFO - 1 warning generated. 08:05:17 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 08:05:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:17 INFO - Successfully installed psutil 08:05:17 INFO - Cleaning up... 08:05:17 INFO - Return code: 0 08:05:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05: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')]} 08:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:05: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 mozsystemmonitor==0.3 08:05:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:17 INFO - 'HOME': '/Users/cltbld', 08:05:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:17 INFO - 'LOGNAME': 'cltbld', 08:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:17 INFO - 'MOZ_NO_REMOTE': '1', 08:05:17 INFO - 'NO_EM_RESTART': '1', 08:05:17 INFO - 'PAGER': '/bin/cat', 08:05:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:17 INFO - 'PWD': '/builds/slave/test', 08:05:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:17 INFO - 'SHELL': '/bin/bash', 08:05:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:17 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:17 INFO - 'USER': 'cltbld', 08:05:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:17 INFO - 'XPC_FLAGS': '0x0', 08:05:17 INFO - 'XPC_SERVICE_NAME': '0', 08:05:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:05:17 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 08:05:17 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 08:05:17 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 08:05:17 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 08:05:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:05:19 INFO - Installing collected packages: mozsystemmonitor 08:05:19 INFO - Running setup.py install for mozsystemmonitor 08:05:19 INFO - Successfully installed mozsystemmonitor 08:05:19 INFO - Cleaning up... 08:05:19 INFO - Return code: 0 08:05:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:05:19 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')]} 08:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:19 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')]} 08:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:19 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:19 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 08:05:19 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 08:05:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:19 INFO - 'HOME': '/Users/cltbld', 08:05:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:19 INFO - 'LOGNAME': 'cltbld', 08:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:19 INFO - 'MOZ_NO_REMOTE': '1', 08:05:19 INFO - 'NO_EM_RESTART': '1', 08:05:19 INFO - 'PAGER': '/bin/cat', 08:05:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:19 INFO - 'PWD': '/builds/slave/test', 08:05:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:19 INFO - 'SHELL': '/bin/bash', 08:05:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:19 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:19 INFO - 'USER': 'cltbld', 08:05:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:19 INFO - 'XPC_FLAGS': '0x0', 08:05:19 INFO - 'XPC_SERVICE_NAME': '0', 08:05:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:19 INFO - Downloading/unpacking jsonschema==2.5.1 08:05:19 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 08:05:19 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 08:05:19 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 08:05:19 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 08:05:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:05:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:05:21 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 08:05:21 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 08:05:21 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 08:05:21 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 08:05:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:05:21 INFO - warning: no files found matching '*.txt' 08:05:21 INFO - no previously-included directories found matching 'build' 08:05:21 INFO - no previously-included directories found matching 'dist' 08:05:21 INFO - no previously-included directories found matching '.git*' 08:05:21 INFO - Installing collected packages: jsonschema, functools32 08:05:21 INFO - Running setup.py install for functools32 08:05:21 INFO - warning: no files found matching '*.txt' 08:05:21 INFO - no previously-included directories found matching 'build' 08:05:21 INFO - no previously-included directories found matching 'dist' 08:05:21 INFO - no previously-included directories found matching '.git*' 08:05:21 INFO - Successfully installed jsonschema functools32 08:05:21 INFO - Cleaning up... 08:05:21 INFO - Return code: 0 08:05:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05: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')]} 08:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:21 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:21 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 08:05:21 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 08:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:21 INFO - 'HOME': '/Users/cltbld', 08:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:21 INFO - 'LOGNAME': 'cltbld', 08:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:21 INFO - 'MOZ_NO_REMOTE': '1', 08:05:21 INFO - 'NO_EM_RESTART': '1', 08:05:21 INFO - 'PAGER': '/bin/cat', 08:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:21 INFO - 'PWD': '/builds/slave/test', 08:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:21 INFO - 'SHELL': '/bin/bash', 08:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:21 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:21 INFO - 'USER': 'cltbld', 08:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:21 INFO - 'XPC_FLAGS': '0x0', 08:05:21 INFO - 'XPC_SERVICE_NAME': '0', 08:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:05:21 INFO - Cleaning up... 08:05:22 INFO - Return code: 0 08:05:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:05:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:05:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:22 INFO - 'HOME': '/Users/cltbld', 08:05:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:22 INFO - 'LOGNAME': 'cltbld', 08:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:22 INFO - 'MOZ_NO_REMOTE': '1', 08:05:22 INFO - 'NO_EM_RESTART': '1', 08:05:22 INFO - 'PAGER': '/bin/cat', 08:05:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:22 INFO - 'PWD': '/builds/slave/test', 08:05:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:22 INFO - 'SHELL': '/bin/bash', 08:05:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:22 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:22 INFO - 'USER': 'cltbld', 08:05:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:22 INFO - 'XPC_FLAGS': '0x0', 08:05:22 INFO - 'XPC_SERVICE_NAME': '0', 08:05:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:22 INFO - Downloading/unpacking blobuploader==1.2.4 08:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:23 INFO - Downloading blobuploader-1.2.4.tar.gz 08:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:05:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:05:23 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 08:05:23 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 08:05:23 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 08:05:23 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 08:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:05:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:05:24 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 08:05:24 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 08:05:24 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 08:05:24 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 08:05:24 INFO - Downloading docopt-0.6.1.tar.gz 08:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:05:24 INFO - Installing collected packages: blobuploader, requests, docopt 08:05:24 INFO - Running setup.py install for blobuploader 08:05:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:05:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:05:24 INFO - Running setup.py install for requests 08:05:25 INFO - Running setup.py install for docopt 08:05:25 INFO - Successfully installed blobuploader requests docopt 08:05:25 INFO - Cleaning up... 08:05:25 INFO - Return code: 0 08:05:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05: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')]} 08:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:25 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:25 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 08:05:25 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 08:05:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:25 INFO - 'HOME': '/Users/cltbld', 08:05:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:25 INFO - 'LOGNAME': 'cltbld', 08:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:25 INFO - 'MOZ_NO_REMOTE': '1', 08:05:25 INFO - 'NO_EM_RESTART': '1', 08:05:25 INFO - 'PAGER': '/bin/cat', 08:05:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:25 INFO - 'PWD': '/builds/slave/test', 08:05:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:25 INFO - 'SHELL': '/bin/bash', 08:05:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:25 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:25 INFO - 'USER': 'cltbld', 08:05:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:25 INFO - 'XPC_FLAGS': '0x0', 08:05:25 INFO - 'XPC_SERVICE_NAME': '0', 08:05:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:25 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-IbXfm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:25 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-bcqUan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:25 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-R0ZCtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-FItlGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-27VjFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-pcWQkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ApgJ_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-xlIK8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-z3lbLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-2KrKiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-i1ir7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ujhh_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:26 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Xud2Am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:27 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-gKZwAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:27 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-5Qbtyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:27 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-q_OVw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:27 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-zbuHLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:05:27 INFO - Running setup.py install for manifestparser 08:05:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:05:27 INFO - Running setup.py install for mozcrash 08:05:27 INFO - Running setup.py install for mozdebug 08:05:27 INFO - Running setup.py install for mozdevice 08:05:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Running setup.py install for mozfile 08:05:28 INFO - Running setup.py install for mozhttpd 08:05:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Running setup.py install for mozinfo 08:05:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Running setup.py install for mozInstall 08:05:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Running setup.py install for mozleak 08:05:28 INFO - Running setup.py install for mozlog 08:05:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:05:28 INFO - Running setup.py install for moznetwork 08:05:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:05:29 INFO - Running setup.py install for mozprocess 08:05:29 INFO - Running setup.py install for mozprofile 08:05:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:05:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:05:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:05:29 INFO - Running setup.py install for mozrunner 08:05:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:05:29 INFO - Running setup.py install for mozscreenshot 08:05:29 INFO - Running setup.py install for moztest 08:05:29 INFO - Running setup.py install for mozversion 08:05:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:05:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:05:30 INFO - Cleaning up... 08:05:30 INFO - Return code: 0 08:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05: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')]} 08:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05: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')]} 08:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:30 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:30 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 08:05:30 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 08:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:30 INFO - 'HOME': '/Users/cltbld', 08:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:30 INFO - 'LOGNAME': 'cltbld', 08:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:30 INFO - 'MOZ_NO_REMOTE': '1', 08:05:30 INFO - 'NO_EM_RESTART': '1', 08:05:30 INFO - 'PAGER': '/bin/cat', 08:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:30 INFO - 'PWD': '/builds/slave/test', 08:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:30 INFO - 'SHELL': '/bin/bash', 08:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:30 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:30 INFO - 'USER': 'cltbld', 08:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:30 INFO - 'XPC_FLAGS': '0x0', 08:05:30 INFO - 'XPC_SERVICE_NAME': '0', 08:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-puPAmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:30 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)) 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mSo6f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:30 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)) 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-j907zV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:30 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)) 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-J87XVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:30 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)) 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-SLfiYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:30 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)) 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mG28vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:30 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)) 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:30 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-bv0xHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-GCZpMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-PSJ0nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cFZlEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-XvMKTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-rU8mAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-CFl2wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-fE7jOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-QDLrlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:31 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)) 08:05:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-uTocOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:32 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)) 08:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-E9ZxiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:32 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)) 08:05:32 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)) 08:05:32 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)) 08:05:32 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)) 08:05:32 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)) 08:05:32 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)) 08:05:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:33 INFO - Downloading blessings-1.6.tar.gz 08:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:05:33 INFO - Installing collected packages: blessings 08:05:33 INFO - Running setup.py install for blessings 08:05:33 INFO - Successfully installed blessings 08:05:33 INFO - Cleaning up... 08:05:33 INFO - Return code: 0 08:05:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:34 INFO - Reading from file tmpfile_stdout 08:05:34 INFO - Current package versions: 08:05:34 INFO - blessings == 1.6 08:05:34 INFO - blobuploader == 1.2.4 08:05:34 INFO - docopt == 0.6.1 08:05:34 INFO - functools32 == 3.2.3-2 08:05:34 INFO - jsonschema == 2.5.1 08:05:34 INFO - manifestparser == 1.1 08:05:34 INFO - mozInstall == 1.12 08:05:34 INFO - mozcrash == 1.0 08:05:34 INFO - mozdebug == 0.1 08:05:34 INFO - mozdevice == 0.49 08:05:34 INFO - mozfile == 1.2 08:05:34 INFO - mozhttpd == 0.7 08:05:34 INFO - mozinfo == 0.9 08:05:34 INFO - mozleak == 0.1 08:05:34 INFO - mozlog == 3.4 08:05:34 INFO - moznetwork == 0.27 08:05:34 INFO - mozprocess == 0.25 08:05:34 INFO - mozprofile == 0.28 08:05:34 INFO - mozrunner == 6.13 08:05:34 INFO - mozscreenshot == 0.1 08:05:34 INFO - mozsystemmonitor == 0.3 08:05:34 INFO - moztest == 0.8 08:05:34 INFO - mozversion == 1.4 08:05:34 INFO - psutil == 3.1.1 08:05:34 INFO - requests == 1.2.3 08:05:34 INFO - wsgiref == 0.1.2 08:05:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:34 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')]} 08:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:34 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')]} 08:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:34 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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:34 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 08:05:34 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 08:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:34 INFO - 'HOME': '/Users/cltbld', 08:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:34 INFO - 'LOGNAME': 'cltbld', 08:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:34 INFO - 'MOZ_NO_REMOTE': '1', 08:05:34 INFO - 'NO_EM_RESTART': '1', 08:05:34 INFO - 'PAGER': '/bin/cat', 08:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:34 INFO - 'PWD': '/builds/slave/test', 08:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:34 INFO - 'SHELL': '/bin/bash', 08:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:34 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:34 INFO - 'USER': 'cltbld', 08:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:34 INFO - 'XPC_FLAGS': '0x0', 08:05:34 INFO - 'XPC_SERVICE_NAME': '0', 08:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 INFO - Cleaning up... 08:05:34 INFO - Return code: 0 08:05:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:05:34 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')]} 08:05:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:34 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')]} 08:05:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1064c1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1064c4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcda3490e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, '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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 08:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:05:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:34 INFO - 'HOME': '/Users/cltbld', 08:05:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:34 INFO - 'LOGNAME': 'cltbld', 08:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:34 INFO - 'MOZ_NO_REMOTE': '1', 08:05:34 INFO - 'NO_EM_RESTART': '1', 08:05:34 INFO - 'PAGER': '/bin/cat', 08:05:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:34 INFO - 'PWD': '/builds/slave/test', 08:05:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:34 INFO - 'SHELL': '/bin/bash', 08:05:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:34 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:34 INFO - 'USER': 'cltbld', 08:05:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:34 INFO - 'XPC_FLAGS': '0x0', 08:05:34 INFO - 'XPC_SERVICE_NAME': '0', 08:05:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:05:34 INFO - Cleaning up... 08:05:34 INFO - Return code: 0 08:05:34 INFO - Running post-action listener: _resource_record_post_action 08:05:34 INFO - Running post-action listener: _start_resource_monitoring 08:05:34 INFO - Starting resource monitoring. 08:05:34 INFO - [mozharness: 2017-05-30 15:05:34.716381Z] Finished create-virtualenv step (success) 08:05:34 INFO - [mozharness: 2017-05-30 15:05:34.717218Z] Running install step. 08:05:34 INFO - Running pre-action listener: _resource_record_pre_action 08:05:34 INFO - Running main action method: install 08:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:34 INFO - Reading from file tmpfile_stdout 08:05:34 INFO - Detecting whether we're running mozinstall >=1.0... 08:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:05:35 INFO - Reading from file tmpfile_stdout 08:05:35 INFO - Output received: 08:05:35 INFO - Usage: mozinstall [options] installer 08:05:35 INFO - Options: 08:05:35 INFO - -h, --help show this help message and exit 08:05:35 INFO - -d DEST, --destination=DEST 08:05:35 INFO - Directory to install application into. [default: 08:05:35 INFO - "/builds/slave/test"] 08:05:35 INFO - --app=APP Application being installed. [default: firefox] 08:05:35 INFO - mkdir: /builds/slave/test/build/application 08:05:35 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'] 08:05:35 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 08:05:55 INFO - Reading from file tmpfile_stdout 08:05:55 INFO - Output received: 08:05:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:05:55 INFO - Running post-action listener: _resource_record_post_action 08:05:55 INFO - [mozharness: 2017-05-30 15:05:55.485202Z] Finished install step (success) 08:05:55 INFO - [mozharness: 2017-05-30 15:05:55.485374Z] Running run-tests step. 08:05:55 INFO - Running pre-action listener: _resource_record_pre_action 08:05:55 INFO - Running main action method: run_tests 08:05:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:05:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:05:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:05:55 INFO - 2017-05-30 08:05:55.499 screenresolution[1629:8548] starting screenresolution argv=screenresolution get 08:05:55 INFO - 2017-05-30 08:05:55.502 screenresolution[1629:8548] Display 0: 1600x1200x32@60 08:05:55 INFO - 2017-05-30 08:05:55.508 screenresolution[1630:8551] starting screenresolution argv=screenresolution list 08:05:55 INFO - Available Modes on Display 0 08:05:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:05:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:05:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:05:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:05:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:05:55 INFO - Intel Iris: 08:05:55 INFO - Chipset Model: Intel Iris 08:05:55 INFO - Type: GPU 08:05:55 INFO - Bus: Built-In 08:05:55 INFO - VRAM (Dynamic, Max): 1536 MB 08:05:55 INFO - Vendor: Intel (0x8086) 08:05:55 INFO - Device ID: 0x0a2e 08:05:55 INFO - Revision ID: 0x0009 08:05:55 INFO - Displays: 08:05:55 INFO - Display: 08:05:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:05:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:05:55 INFO - Display Serial Number: 1600x1200 60 08:05:55 INFO - Main Display: Yes 08:05:55 INFO - Mirror: Off 08:05:55 INFO - Online: Yes 08:05:55 INFO - Rotation: Supported 08:05:55 INFO - Return code: 0 08:05:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:05:55 INFO - Python 2.7.3 08:05:55 INFO - Return code: 0 08:05:55 INFO - grabbing minidump binary from tooltool 08:05:55 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')]} 08:05:55 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 0x10629fd30>, '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 0x1061c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106294be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:05:55 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 08:05:55 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 08:05:55 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 08:05:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:05:55 INFO - Return code: 0 08:05:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:05:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:05:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:05:55 INFO - ENV: RUST_BACKTRACE is now 1 08:05:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:05:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:05:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 08:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --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 08:05:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zGZ5cT5cz5/Render', 08:05:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:55 INFO - 'HOME': '/Users/cltbld', 08:05:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:55 INFO - 'LOGNAME': 'cltbld', 08:05:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:05:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:55 INFO - 'MOZ_NO_REMOTE': '1', 08:05:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:05:55 INFO - 'NO_EM_RESTART': '1', 08:05:55 INFO - 'PAGER': '/bin/cat', 08:05:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:55 INFO - 'PWD': '/builds/slave/test', 08:05:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:05:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:55 INFO - 'RUST_BACKTRACE': '1', 08:05:55 INFO - 'SHELL': '/bin/bash', 08:05:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIw9TvGEl1/Listeners', 08:05:55 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 08:05:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:55 INFO - 'USER': 'cltbld', 08:05:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:55 INFO - 'XPC_FLAGS': '0x0', 08:05:55 INFO - 'XPC_SERVICE_NAME': '0', 08:05:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 08:05:56 INFO - mozversion application_buildid: 20170530071016 08:05:56 INFO - mozversion application_changeset: 3524778fe631486d5a9cd4f5392177f297b337dc 08:05:56 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:05:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:05:56 INFO - mozversion application_name: Firefox 08:05:56 INFO - mozversion application_remotingname: firefox-dev 08:05:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:05:56 INFO - mozversion application_vendor: Mozilla 08:05:56 INFO - mozversion application_version: 54.0 08:05:56 INFO - mozversion platform_buildid: 20170530071016 08:05:56 INFO - mozversion platform_changeset: 3524778fe631486d5a9cd4f5392177f297b337dc 08:05:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:05:56 INFO - mozversion platform_version: 54.0 08:05:56 INFO - using testdate: 1496156756 08:05:56 INFO - actual date: 1496156756 08:05:56 INFO - starting webserver on 'localhost:49233' 08:05:56 INFO - SUITE-START | Running 2 tests 08:05:56 INFO - TEST-START | dromaeo_css 08:05:56 INFO - operating with platform_type : mac_ 08:05:56 INFO - Initialising browser for dromaeo_css test... 08:05:56 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpdgcFpv/profile http://localhost:49233/getInfo.html) 08:05:58 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 08:05:58 INFO - PROCESS | 1640 | colorDepth:24 08:05:58 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 08:05:58 INFO - PROCESS | 1640 | __metrics 08:05:58 INFO - PROCESS | 1640 | 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" 08:05:58 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:05:59 INFO - TEST-INFO | 1640: exit 0 08:05:59 INFO - Browser initialized. 08:05:59 INFO - Running cycle 1/1 for dromaeo_css test... 08:05:59 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpdgcFpv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:06:11 INFO - PROCESS | 1644 | RSS: Main: 193925120 08:06:11 INFO - PROCESS | 1644 | 08:09:15 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 08:09:17 INFO - PROCESS | 1644 | RSS: Main: 331440128 08:09:17 INFO - PROCESS | 1644 | 08:12:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 08:12:20 INFO - PROCESS | 1644 | RSS: Main: 333484032 08:12:20 INFO - PROCESS | 1644 | 08:15:23 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 08:15:23 INFO - PROCESS | 1644 | RSS: Main: 325685248 08:15:23 INFO - PROCESS | 1644 | 08:18:26 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 08:18:26 INFO - PROCESS | 1644 | RSS: Main: 294043648 08:18:26 INFO - PROCESS | 1644 | 08:21:30 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 08:21:30 INFO - PROCESS | 1644 | RSS: Main: 293924864 08:21:30 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 08:24:33 INFO - PROCESS | 1644 | RSS: Main: 491720704 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | __start_tp_report 08:24:33 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 08:24:33 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 08:24:33 INFO - PROCESS | 1644 | |i|pagename|runs| 08:24:33 INFO - PROCESS | 1644 | |0;dojo.html;3396.3716712408204;3422.6577342265778;3427.7429192810546;3453.1367158210446;3480.1995541025562;5950.315713692927;5963.40365963404;5964.79123230687;5969.015112506431;6005.369436209196;5926.348101708809;5973.88052238955;6004.549658775588;6006.609570377922;6010.849728756783;5325.840224793263;5363.088274993241;5382.461753824626;5426.430224826376;5483.588730845183;5269;5273.736313184345;5290.92063619046;5434.456554344553;5461.0716178249595;5347.946520534783;5363.463653634644;5374.247605335257;5382.192671099347;5402.6758394496455;5400;5427.402985671612;5437.6465529740635;5439.347278326587;5439.456054394568;5264.710440925747;5329.733513324319;5385.569154467633;5395.974764794676;5458.590605704588;5750.309962804448;5783.421657834225;5791.015527360359;5802.709864506758;6003.399660034005;6192.256929168483;6225.533085018642;6247.281562620311;6320.462760665349;6348.1430006949395;6308.716107775115;6349.619022858643;6353.745850165988;6361.2048493938255;6362.840928976812;5990.820275391745;6024.307204671475;6059.151718759377;6072.939270607326;6075.696215189223;6279.058141278845;6279.529035322369;6315.336889626615;6348.841278968062;6388.8083357499345;6355.809325720235;6365.2361716594305;6371.5221358397375;6374.872502550016;6377.298497165308;6180.381961803783;6275.995840665563;6315.494760419156;6324;6352.968235158794;6219.191505104308;6256.374362563707;6285.842853928597;6355;6366.045093236052;6230.875382492325;6267.2165979252595;6274.874502509924;6310.6213627182515;6337.7781777637565;4868.634852386085;4877;4911.336969509142;4916.213405855046;4917.852464426073;4381.079973205662;4381.890452738707;4394.582514661102;4427.911441771211;4458.888527786831;5605.0751625982075;5613.522850557667;5688.857778555486;5791.942080579224;5805.506531944832;6277.7175027123885;6320.494360451153;6372.872542549216;6374.07575901501;6377.23473183221;5789.768409263583;5798.246227990334;5802.129680547867;5807.0998995155815;5852.590318677737;5336.493033161843;5464.808731694372;5475.123980163155;5476.972615136899;5491.0116179087345;6275.905861411991;6325.746970121328;6377.681115944277;6423.357664233727;6430.131932189094;6343.111964324906;6351.96824015877;6352.205974253218;6352.9047064295;6359.300476947439;6445.452136568444;6463.57986730861;6479.287278399466;6494.058361537645;6499.772507962199;6194.4115309045565;6206.875862482815;6276.403741644536;6303.810885673437;6328.9050664240995;6328;6346.777862774781;6354.6504942226775;6354.841128971628;6387.425131738166;6318.557700960889;6325.936740632535;6334.3665633436285;6348.682565871781;6352.110704501466;6145.156453064153;6203.503719702323;6284.3087260402235;6284.968575157095;6365.522585806176;6143.573288551087;6206.789676013134;6210.347913469021;6223;6228.096925945803;6112.929053818996;6328.810135695937;6335.873282534416;6349.269833969152;6352.301246862934;6012.849678757891;6045.153678485103;6115.816525504242;6134.509239260759;6140.938590614037 08:24:33 INFO - PROCESS | 1644 | |1;ext.html;777.8716511775557;809.7085049382222;829.6971605364042;886.0474989386407;900.4687234531625;28072;28413.57379639307;29223;40174.19651606966;40856.59143408565;41604.16791664172;41859.16281674365;41924;41924.79037604816;42070.7896460518;9459.763505912373;9643.90356096437;9667.854982175273;9672.790901137358;9683.176929960962;7699.345555627779;7854.764357069268;9516.38143520669;9633.903660963406;11555.017823485014;11783.175177737561;11824.290542567473;11824.645260642152;11825.645230643051;11852;11723.47244374001;11780.293182409083;11784.292942423483;11787.115966302561;11789.528418863236;11395.316281023164;11446.542138314457;11456.942715286454;11458;11474.770504589862;39449;39544.40683389751;39591.40612890809;39597.80201098976;39615.80192099021;3424.1004376182013;3957.52509698838;3979.1047014421815;4018.7789671568053;4061.878143655695;27545.44909101827;27552.311192220153;27596.310092247855;29613.259668508243;29655;3035.620547431571;3218.4850423932294;3306.553615261933;3312.668733126692;3315.055209265372;364598;364804;364969;364973;365262.9999999973;52928.20607690888;53138.46861531412;53208;53210.46789532055;53271.73364133154;53763;55057.724711376184;55059.72470137624;55125.44874551203;55158;13136.474541018444;15016.24918754058;15016.324265407864;15061;15067.171305578298;13002.89475394602;13042.869571304165;13047.086703930823;13067.281299528428;13072.477100915858;8675.436096653697;10168.644097456554;10381.273310868317;11697.473613687405;12032.759344813056;4530.28794749844;5675.0636145035605;5711.257536520226;5718.199452076713;5753.482186603226;922.8846394200725;941.4539567051094;963.1235575626147;997.7056768253383;997.7904640025531;26958.5956210657;27756.30609234785;28225.576616350772;28307.858460707976;28315.858420708177;931;1053.6048981631889;1091.4542728635681;1095.6767753512734;1097.6322931817772;14117.43530258778;14433.855661443462;14454.349554270084;14752.778708319383;14757.335919883632;414465;415513.9224303859;416627;416915;418728.9063554663;55710;55771.16343254854;55905.440945590024;55906.44093559094;56372.15441768377;1254.1722463174444;1277.897768178525;1463.9560813175622;1478.5194811686245;1485.688005518853;26771.33071673224;27986.72013279841;30123.096307110824;30377.69622303749;30600.84699576577;42428.36357454579;42599.7870010648;42606.5739342615;42632.36051459169;42633.36049959315;368320;369039;369387.15306423296;369826;370519;27344;27801.304967375167;35769;38970.61029389783;40414.311491261564;46761.76619116883;48289.75855120702;48377;48977.755111224214;49705.50294497009;31932.680673192972;31936.840315798272;32042;33143.83996560109;33496.497552536246;31887.36225275528;32178.88065696629;32655.020349389557;32741.672583273863;32974.67025329716;39341;39896.40155397613;40169.79915100522;40270.1945961085;40300.79849600851;32367.190820229684;32640.510392344608;32691.50962735513;33640.327193456484;33775.83112084424;22240.110395583997;23775.76224237805;25466.981320747705;26042.95828166928;26733.19800405991 08:24:33 INFO - PROCESS | 1644 | |2;jquery.html;379.37213910977334;414.90457194845203;424.17921322241483;430.8664314062641;458.9839355622554;20956;21914.780852191474;22185.889070554626;22301;22377.664335034948;21252.787472125274;22784.43038924028;23184.42038949027;23511.647325290134;23774.0490380385;6824.385805277524;6902.654867256655;7078.292170782932;7213.531120477179;7221.350078492935;6981.650917454107;7114.644267786616;7121.465890058241;7130.144382674061;7272.63636818157;7721.72973945912;7774;7776;7778.183290754474;7780.727674531393;7502.287282708134;7659.213838829493;7692.423068269902;7753.573553454557;7760.495567788075;7227.819304517377;7392;7430.145533263636;7444.404447644176;7475.476716629838;21839.890800546054;25701;25754.74245257542;26170;26602.33494162642;2962.5111856543645;2980.5231163013814;3110.7355874750674;3112.2686168750324;3131.827749473778;14217.502387416494;16497.752533712002;16589.83410165895;16676;17142.657146856993;2063.7420322459693;2094.979050209494;2125.171183238538;2152.53720450104;2221.3780141560387;85364.08816428084;109212.45393773059;134081;139732;164484.17757911133;16629.83370166283;19374.99687548432;20164.580250369756;20625.17499300041;20801.687974680393;31570.526442103386;31638.36723265522;31995.360092798015;32068.679313207034;32109;8163.3061189798345;10063.69808905734;10293.279470436997;10413.323133996266;10665.946670266754;8661.740147795575;8677.39258251929;8685.174908383693;8821.647134114564;8828.117188281121;7744.380449564022;9386.812263754686;9442.291828112919;9542;9551.997040310667;3321.7508686848582;3939.5075615548058;4007.198560287896;4015.477987861559;4019.3167161582014;790.6284046498137;884.5975081337975;888.200619442494;915.8259930613162;916.3173435790379;21068.367948961557;21705.782942170375;21790;21807.67288490674;21909.151810264564;931.417863835103;933.8785957825575;952.3048174832411;955.6273053509137;973.2846357926918;10195.694129176136;10803.351798892092;10896.455177241107;11337.696164940973;11420.543178272943;742352;753245;753351;755105;768169;144615.83076253865;144904;147755;149596.2520187392;150717;1209.5585111434439;1232.5439587352737;1246.8254444377794;1247.9126461147632;1261.1298204239047;15587.844121558639;17203.483895483154;18488.260469581604;18511.629767404847;18921.71617425767;62012.68993655003;62178;62987;63952.68023660038;64224.07327780174;87468.56265718631;87601.68597470915;87784;87900.5604971971;90750.54624727098;9198.218151456933;11505.252158609663;11574.131940104357;11626.593069242535;12024.458899349374;13065.869341306465;13099.934500327437;13125.934370328087;13324.267165306;13379.732405351644;11189.832152517556;11376.146788990693;11449.828252576384;11538;11574.594889179176;10364.948175259075;10496.317739346918;10668.03987641116;10758.838617420588;10764.461776910966;11421.37182454973;11651.126165537788;11747.588834390755;11826.113041521749;11850.288982661068;10308.484575771332;11417.858214178515;11421.942890285827;11518.251313664585;11611.593594224161;9302.720918372459;10732.946335268274;11270.211085223955;11516.82724759146;11592.88407115918 08:24:33 INFO - PROCESS | 1644 | |3;mootools.html;1364.2360278244178;1445.5229774174522;1599.0086146589117;1627.8697704183667;1643.3590899549179;9277.536123193839;9713.32006759527;10878.347299162057;11129.833052504198;11212.831807522894;11029.724256893562;11115.88841115888;11131.220814542965;11131.276467029638;11178.888211117886;2326.0463210083913;2370.9051637934463;2371.1819422299277;2371.490129622131;2373.774491423312;1967.9606407871834;2060.53637931465;2313.9537209255805;2388.474535602165;2399.7360290368133;2340.7425183229916;2353.482233908537;2354.117206047732;2360.5868972929757;2362.3267368799898;2303.216906251874;2326.325365643961;2332.5568142053125;2336.439254578906;2337.403961989699;2290.267114523353;2303.976960230393;2306.4810417656063;2307.204014614955;2321.373229228099;11225.32648041112;11287.717807054807;11309.830352544635;11315.264507806965;11315.603953861657;5516;5716.28546431696;5716.771329146829;5722.941255867678;5727.398623144593;3118.485449900764;3243.254051568129;3490.89527314181;3497.45789402643;3506.7895926244505;1070.5664205996584;1124.454639499842;1164.5865717670274;1172.9648110556698;1187.6258978421856;14841.183734894577;15237.542873713806;15686.450974215848;15773.842261577467;15797.526074217792;4453.1316393303;4547.590716835501;4574.6340292776495;4581.74800385982;4591.586757191869;10351.378917264989;11545.365004924643;11774;11793.351365674971;11794.233374830608;5612.97193514038;5613.887722245614;5614.29821272341;5623.746931388097;5625.606207565513;4344.56554344563;5044.798208071636;5091;5145.871353216125;5218.321618189611;3367.5285460035616;3453.4129198036394;3705.740598158157;3772.86794962175;3790.166163444038;947.4173383369179;950.2635457520339;960.4573416019927;976.1848856205006;976.584951395654;821.8561751693429;882.2016075451727;898.0884402331508;900.8333458310234;918.2653876898454;6631.86736265272;6670.866582668319;6679.532432729759;6851.828704282333;6880.415164711056;919.4069824962995;928.8513837785922;1040.4173662748885;1052.4842827014709;1081.2107161771794;4170.145120250341;4199.307114326163;4264.552222016706;4344.261475549164;4390.560943905584;13404.329783510786;13951.02342836012;13956.860431395557;13970.580882573538;14013.369398377097;13141.737165256642;13532.120412173177;13898.652533686738;13908.791368129487;13988.020838541408;10064.194864410976;10323.793524129625;10357.119644830274;10379.740506487247;10383.584656613803;5920.674362910081;6502.642354670538;6658.201015878126;6725.630090345078;6932.202796678245;11929.105317101426;12218.999999999643;12361.258324500559;12365.010799135867;12378.690532736393;12232.449539770554;12446.688832779253;12647.936760316139;12675.93662031684;12692;9108;9284.442933424016;9285.535723213678;9288.628454862;9985.45080020606;10484.42335671515;10562.471876406302;10801.297915635772;10823.621173258898;10835.404052777172;8627.35294852876;8730.214280714767;8823.64705411802;8850.291976641724;8969.609710494884;8622.137786221328;8649.8702519461;8709.99505170715;8734.868976965477;8741.868871967054;7973.521588704696;8128.593570321342;8128.715494957648;8139.511629302261;8141.959290203511;5802.1586869902785;5849.590528662953;5862.677552734641;5877.559183061373;6102.359252278447;6029.336772955059;6033.78881739137;6203.658798766111;6240.157578726814;6359.395857393541 08:24:33 INFO - PROCESS | 1644 | |4;prototype.html;374.37853163748184;381.7938313310812;384.2296196126765;390.7011136480591;392.1177350960338;4370.562943705628;4372.519022907478;4891.923776769105;4971.154903666376;5138.537531622158;3989.840406383741;4418.293073108295;4753.500882407349;5075.857931965306;5079.492050794928;3805.6955443564516;4221.620054195122;4319.913601727963;4325.567443255665;4329.393884856123;3877.983448606737;3961.8217180226816;4304.332828411586;4335.937695264664;4362.214801335758;4156.334986402177;4295.591918767712;4343.63079138272;4344.239758042346;4356.607905288523;3693.8891833245048;4251.97874010631;4294.699371044028;4298.441202643668;4314.697971142022;3932.8426862925735;3950.8147555733167;3951.905490090257;3966.960330396717;4069.7151199416053;4149.97925010373;5129.8717532062;5141.305923700327;5142.640015198937;5181.119209734355;4620.514845941195;4635.804596839866;4695.319178719101;4912.656114072016;4999.000199959986;4670.509596492353;4795.211782691669;4936.569773249433;5270.57835373173;5521;4168.228877657643;4415.933760993587;4429.6744209290055;4430.335449682573;4451.28471632304;4952.182889823175;5546.69493177875;5620.213170156181;5768.913466298009;5912.82261532159;3759.3045286621514;4157.376393540993;4494.550544945545;4579.351433483943;4590.84230411706;3860.478835357247;4045.9797701011307;4077.4903137107863;4093.891645802414;4141.772202528829;4138.482689663792;4197.622214000755;4469.7318160910445;4536.280445985915;4546.499885012646;5488.835334939959;5492.450754924475;5508.14623733322;5538.501534861882;5544.91682624761;1419.6876687128815;1423.665438621923;1553.4562902983864;1579.7125342845545;1582.8603405547988;1069.79139067881;1087.597588892115;1088.3361149698678;1176.8117101263756;1204.27141579345;4612.97693511537;4773.928391074137;4920.620647623547;4933.777979990909;4993.875153121128;5492.423295553909;5677.460641239159;5689.065717656017;5705.857353566194;5768;4491.92193873468;4642.313166371168;4911.607071434277;4912.778924948386;4958.776855041532;2949.2626843289177;2952.926176845553;2954.5568164775455;2955.704429557027;2955.9556606650917;5147.525420341255;5367.194920761917;5495.780168793283;5877.882442351129;5899.941000590025;5089.447718445836;5130;5447.214186140594;5525.364583072917;5834;5262.184731168959;5344.5724342052545;5629.7185140742795;5713.857153571194;5926.555508336805;4784.593309568726;5189.870253243699;5651.8304450866535;5657.32112146545;5676;5295.973520132375;5371.62398632092;5570.6657600544095;5643.407442218672;5646.205128461438;4985.177445721415;5590.211132437581;5892.5285977122585;5928.347881733092;5937.101890939905;3961.2372494239785;3993.740406873544;4409.867703968886;4421;4505.008898042425;4832.855014349575;5349.545288650508;5622.8313150605545;5663.440930051183;5735.971320143372;4868.442098528437;5354.83129332621;5396.109641909041;5405.135178371442;5542.8914217155925;4681.485036646034;5130.948690513047;5483.341998960151;5526.889462210653;5538.529225015919;4590.495045545054;5339.279197308314;5362.70505122222;5367.651102678314;5421.647592906448;4950.356453661001;5282.973585132204;5347.101778868446;5531.253280807039;5545.916811247916;5030.723310217973;5332.840014799563;5337.653052551572;5346.278252435872;5388.919166212589 08:24:33 INFO - PROCESS | 1644 | |5;yui.html;677.945764338853;738.1437532462345;764.2357642357642;768.8500742355243;893.1470445724332;13703.58889233322;15493.302801373937;16308.510744677678;16336.93809902357;16339.591510212236;12963.416646250918;12999.675008124801;13014.674633134176;13066.281354525498;13146.276954767483;975.2977855943727;977.8484334928097;994.721477986165;998.101708462386;1011.5043628621996;982.4056445850265;1000.3797645459825;1000.8048430556045;1001.8246806808816;1002.8395456726926;1036.6734478639241;1079.5357996061691;1081.8918108189198;1082.161324973148;1084.230196560439;1051.1223128687554;1051.3691784929058;1056.060106505211;1062.3572738493178;1063.7766069125494;772.231629528617;773.207462351089;773.957432341221;775.3061010395664;776.4176867349488;16010;16352.75470867938;16408;16677.91661041687;16924.66150676992;14956;15153.393864245418;15412;15449.691006179813;15650.295736691876;11438.714032149179;11738.589149379815;11786.35175065371;11926.403679815974;12235.265884046985;3773.9622603773887;3774.5281839770028;3779.4897688812207;3864.3430616795213;3992.800359982018;37582.00308611331;85684.57157714234;110493.44753276263;111317.99999999919;111327;11317.434128293553;11461.480081548825;11595.18833681651;12073.33596652175;12300.569480068154;24689;25014.624780628306;25187.874060629947;25217.369565760637;25220;8225.136360682161;8256.628451719687;8257.504549727035;8315.168483151636;8479.576021198915;4377.846775362847;4466.017476155241;4507.864764057083;4598.839040633562;4698.342232087511;3432.5366075579977;3536.9823150884595;3543.344481270973;3546.716262698978;3547.4324108142573;596.5394774591167;617.3857012272817;618.2333905956582;618.5855476830534;632.5256057956533;668.5053060735021;688.703857341348;690.5856486108295;705.8905869590221;730.7150211417552;9062.365634405529;10344.277813761119;10386.584536618451;10488.206253428476;10620.198539014942;702.5117543307352;724.3661795928563;725.9274072592804;732.783828770514;736.7900148457663;4891.217405215149;5030.245463180479;5034.810958876887;5077.365329333833;5128.076946149728;99846;103586.48206758918;108177.45911270393;108396;110317.44841275743;90348;91449;92733.0726692738;93116.53441732883;93542;68697.65651171912;80531;83176.58411707902;83260;83393;10715.249932504961;10980.780384392427;11240.775184496428;11273.887261127284;11299.887001129884;48387;48802.26796597983;49135;49299.45546325296;49317.50682493272;46033.539664602926;61751.38248617456;62615.747685046954;63069;63136;12746.490140394651;12850.807237891626;12873.291968941423;12876.163049401759;13404.530841420503;13576.389062492013;13892.30538473052;13940.512082077079;14525.128492290496;14709.632259193606;10415.947920260653;13135.408906599418;13807.79288310656;14083.859161408665;14090.436382544583;13253.602391928258;13908.86091139116;14027.649308767363;14078.788818167941;14164.433422662978;12726.618201453972;13127.803082953955;13602.38789254466;13613.183209007491;13925.721485570435;9193.264538837011;9747.9025209747;9750.512474376395;9777.364471309342;9841.703029484916;8531.677589973564;9407.717768466957;9780.462074585967;10102.69691909244;10268.127209187003 08:24:33 INFO - PROCESS | 1644 | __end_tp_report 08:24:33 INFO - PROCESS | 1644 | __start_cc_report 08:24:33 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1440 08:24:33 INFO - PROCESS | 1644 | __end_cc_report 08:24:33 INFO - PROCESS | 1644 | __startTimestamp1496157873950__endTimestamp 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | ------- Summary: start ------- 08:24:33 INFO - PROCESS | 1644 | Number of tests: 6 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5877.36 Median:6145.16 stddev:655.04 (10.7%) stddev-sans-first:627.47 08:24:33 INFO - PROCESS | 1644 | Values: 3396.4 3422.7 3427.7 3453.1 3480.2 5950.3 5963.4 5964.8 5969.0 6005.4 5926.3 5973.9 6004.5 6006.6 6010.8 5325.8 5363.1 5382.5 5426.4 5483.6 5269.0 5273.7 5290.9 5434.5 5461.1 5347.9 5363.5 5374.2 5382.2 5402.7 5400.0 5427.4 5437.6 5439.3 5439.5 5264.7 5329.7 5385.6 5396.0 5458.6 5750.3 5783.4 5791.0 5802.7 6003.4 6192.3 6225.5 6247.3 6320.5 6348.1 6308.7 6349.6 6353.7 6361.2 6362.8 5990.8 6024.3 6059.2 6072.9 6075.7 6279.1 6279.5 6315.3 6348.8 6388.8 6355.8 6365.2 6371.5 6374.9 6377.3 6180.4 6276.0 6315.5 6324.0 6353.0 6219.2 6256.4 6285.8 6355.0 6366.0 6230.9 6267.2 6274.9 6310.6 6337.8 4868.6 4877.0 4911.3 4916.2 4917.9 4381.1 4381.9 4394.6 4427.9 4458.9 5605.1 5613.5 5688.9 5791.9 5805.5 6277.7 6320.5 6372.9 6374.1 6377.2 5789.8 5798.2 5802.1 5807.1 5852.6 5336.5 5464.8 5475.1 5477.0 5491.0 6275.9 6325.7 6377.7 6423.4 6430.1 6343.1 6352.0 6352.2 6352.9 6359.3 6445.5 6463.6 6479.3 6494.1 6499.8 6194.4 6206.9 6276.4 6303.8 6328.9 6328.0 6346.8 6354.7 6354.8 6387.4 6318.6 6325.9 6334.4 6348.7 6352.1 6145.2 6203.5 6284.3 6285.0 6365.5 6143.6 6206.8 6210.3 6223.0 6228.1 6112.9 6328.8 6335.9 6349.3 6352.3 6012.8 6045.2 6115.8 6134.5 6140.9 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:53513.13 Median:200183.50 stddev:101383.95 (50.6%) stddev-sans-first:101591.04 08:24:33 INFO - PROCESS | 1644 | Values: 777.9 809.7 829.7 886.0 900.5 28072.0 28413.6 29223.0 40174.2 40856.6 41604.2 41859.2 41924.0 41924.8 42070.8 9459.8 9643.9 9667.9 9672.8 9683.2 7699.3 7854.8 9516.4 9633.9 11555.0 11783.2 11824.3 11824.6 11825.6 11852.0 11723.5 11780.3 11784.3 11787.1 11789.5 11395.3 11446.5 11456.9 11458.0 11474.8 39449.0 39544.4 39591.4 39597.8 39615.8 3424.1 3957.5 3979.1 4018.8 4061.9 27545.4 27552.3 27596.3 29613.3 29655.0 3035.6 3218.5 3306.6 3312.7 3315.1 364598.0 364804.0 364969.0 364973.0 365263.0 52928.2 53138.5 53208.0 53210.5 53271.7 53763.0 55057.7 55059.7 55125.4 55158.0 13136.5 15016.2 15016.3 15061.0 15067.2 13002.9 13042.9 13047.1 13067.3 13072.5 8675.4 10168.6 10381.3 11697.5 12032.8 4530.3 5675.1 5711.3 5718.2 5753.5 922.9 941.5 963.1 997.7 997.8 26958.6 27756.3 28225.6 28307.9 28315.9 931.0 1053.6 1091.5 1095.7 1097.6 14117.4 14433.9 14454.3 14752.8 14757.3 414465.0 415513.9 416627.0 416915.0 418728.9 55710.0 55771.2 55905.4 55906.4 56372.2 1254.2 1277.9 1464.0 1478.5 1485.7 26771.3 27986.7 30123.1 30377.7 30600.8 42428.4 42599.8 42606.6 42632.4 42633.4 368320.0 369039.0 369387.2 369826.0 370519.0 27344.0 27801.3 35769.0 38970.6 40414.3 46761.8 48289.8 48377.0 48977.8 49705.5 31932.7 31936.8 32042.0 33143.8 33496.5 31887.4 32178.9 32655.0 32741.7 32974.7 39341.0 39896.4 40169.8 40270.2 40300.8 32367.2 32640.5 32691.5 33640.3 33775.8 22240.1 23775.8 25467.0 26043.0 26733.2 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:42375.43 Median:23642.85 stddev:125138.58 (529.3%) stddev-sans-first:125449.90 08:24:33 INFO - PROCESS | 1644 | Values: 379.4 414.9 424.2 430.9 459.0 20956.0 21914.8 22185.9 22301.0 22377.7 21252.8 22784.4 23184.4 23511.6 23774.0 6824.4 6902.7 7078.3 7213.5 7221.4 6981.7 7114.6 7121.5 7130.1 7272.6 7721.7 7774.0 7776.0 7778.2 7780.7 7502.3 7659.2 7692.4 7753.6 7760.5 7227.8 7392.0 7430.1 7444.4 7475.5 21839.9 25701.0 25754.7 26170.0 26602.3 2962.5 2980.5 3110.7 3112.3 3131.8 14217.5 16497.8 16589.8 16676.0 17142.7 2063.7 2095.0 2125.2 2152.5 2221.4 85364.1 109212.5 134081.0 139732.0 164484.2 16629.8 19375.0 20164.6 20625.2 20801.7 31570.5 31638.4 31995.4 32068.7 32109.0 8163.3 10063.7 10293.3 10413.3 10665.9 8661.7 8677.4 8685.2 8821.6 8828.1 7744.4 9386.8 9442.3 9542.0 9552.0 3321.8 3939.5 4007.2 4015.5 4019.3 790.6 884.6 888.2 915.8 916.3 21068.4 21705.8 21790.0 21807.7 21909.2 931.4 933.9 952.3 955.6 973.3 10195.7 10803.4 10896.5 11337.7 11420.5 742352.0 753245.0 753351.0 755105.0 768169.0 144615.8 144904.0 147755.0 149596.3 150717.0 1209.6 1232.5 1246.8 1247.9 1261.1 15587.8 17203.5 18488.3 18511.6 18921.7 62012.7 62178.0 62987.0 63952.7 64224.1 87468.6 87601.7 87784.0 87900.6 90750.5 9198.2 11505.3 11574.1 11626.6 12024.5 13065.9 13099.9 13125.9 13324.3 13379.7 11189.8 11376.1 11449.8 11538.0 11574.6 10364.9 10496.3 10668.0 10758.8 10764.5 11421.4 11651.1 11747.6 11826.1 11850.3 10308.5 11417.9 11421.9 11518.3 11611.6 9302.7 10732.9 11270.2 11516.8 11592.9 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:6740.27 Median:2759.11 stddev:4285.71 (155.3%) stddev-sans-first:4278.69 08:24:33 INFO - PROCESS | 1644 | Values: 1364.2 1445.5 1599.0 1627.9 1643.4 9277.5 9713.3 10878.3 11129.8 11212.8 11029.7 11115.9 11131.2 11131.3 11178.9 2326.0 2370.9 2371.2 2371.5 2373.8 1968.0 2060.5 2314.0 2388.5 2399.7 2340.7 2353.5 2354.1 2360.6 2362.3 2303.2 2326.3 2332.6 2336.4 2337.4 2290.3 2304.0 2306.5 2307.2 2321.4 11225.3 11287.7 11309.8 11315.3 11315.6 5516.0 5716.3 5716.8 5722.9 5727.4 3118.5 3243.3 3490.9 3497.5 3506.8 1070.6 1124.5 1164.6 1173.0 1187.6 14841.2 15237.5 15686.5 15773.8 15797.5 4453.1 4547.6 4574.6 4581.7 4591.6 10351.4 11545.4 11774.0 11793.4 11794.2 5613.0 5613.9 5614.3 5623.7 5625.6 4344.6 5044.8 5091.0 5145.9 5218.3 3367.5 3453.4 3705.7 3772.9 3790.2 947.4 950.3 960.5 976.2 976.6 821.9 882.2 898.1 900.8 918.3 6631.9 6670.9 6679.5 6851.8 6880.4 919.4 928.9 1040.4 1052.5 1081.2 4170.1 4199.3 4264.6 4344.3 4390.6 13404.3 13951.0 13956.9 13970.6 14013.4 13141.7 13532.1 13898.7 13908.8 13988.0 10064.2 10323.8 10357.1 10379.7 10383.6 5920.7 6502.6 6658.2 6725.6 6932.2 11929.1 12219.0 12361.3 12365.0 12378.7 12232.4 12446.7 12647.9 12675.9 12692.0 9108.0 9284.4 9285.5 9288.6 9985.5 10484.4 10562.5 10801.3 10823.6 10835.4 8627.4 8730.2 8823.6 8850.3 8969.6 8622.1 8649.9 8710.0 8734.9 8741.9 7973.5 8128.6 8128.7 8139.5 8142.0 5802.2 5849.6 5862.7 5877.6 6102.4 6029.3 6033.8 6203.7 6240.2 6359.4 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4546.47 Median:4901.77 stddev:1263.42 (25.8%) stddev-sans-first:1227.55 08:24:33 INFO - PROCESS | 1644 | Values: 374.4 381.8 384.2 390.7 392.1 4370.6 4372.5 4891.9 4971.2 5138.5 3989.8 4418.3 4753.5 5075.9 5079.5 3805.7 4221.6 4319.9 4325.6 4329.4 3878.0 3961.8 4304.3 4335.9 4362.2 4156.3 4295.6 4343.6 4344.2 4356.6 3693.9 4252.0 4294.7 4298.4 4314.7 3932.8 3950.8 3951.9 3967.0 4069.7 4150.0 5129.9 5141.3 5142.6 5181.1 4620.5 4635.8 4695.3 4912.7 4999.0 4670.5 4795.2 4936.6 5270.6 5521.0 4168.2 4415.9 4429.7 4430.3 4451.3 4952.2 5546.7 5620.2 5768.9 5912.8 3759.3 4157.4 4494.6 4579.4 4590.8 3860.5 4046.0 4077.5 4093.9 4141.8 4138.5 4197.6 4469.7 4536.3 4546.5 5488.8 5492.5 5508.1 5538.5 5544.9 1419.7 1423.7 1553.5 1579.7 1582.9 1069.8 1087.6 1088.3 1176.8 1204.3 4613.0 4773.9 4920.6 4933.8 4993.9 5492.4 5677.5 5689.1 5705.9 5768.0 4491.9 4642.3 4911.6 4912.8 4958.8 2949.3 2952.9 2954.6 2955.7 2956.0 5147.5 5367.2 5495.8 5877.9 5899.9 5089.4 5130.0 5447.2 5525.4 5834.0 5262.2 5344.6 5629.7 5713.9 5926.6 4784.6 5189.9 5651.8 5657.3 5676.0 5296.0 5371.6 5570.7 5643.4 5646.2 4985.2 5590.2 5892.5 5928.3 5937.1 3961.2 3993.7 4409.9 4421.0 4505.0 4832.9 5349.5 5622.8 5663.4 5736.0 4868.4 5354.8 5396.1 5405.1 5542.9 4681.5 5130.9 5483.3 5526.9 5538.5 4590.5 5339.3 5362.7 5367.7 5421.6 4950.4 5283.0 5347.1 5531.3 5545.9 5030.7 5332.8 5337.7 5346.3 5388.9 08:24:33 INFO - PROCESS | 1644 | 08:24:33 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:20216.10 Median:25202.62 stddev:28890.21 (114.6%) stddev-sans-first:28934.00 08:24:33 INFO - PROCESS | 1644 | Values: 677.9 738.1 764.2 768.9 893.1 13703.6 15493.3 16308.5 16336.9 16339.6 12963.4 12999.7 13014.7 13066.3 13146.3 975.3 977.8 994.7 998.1 1011.5 982.4 1000.4 1000.8 1001.8 1002.8 1036.7 1079.5 1081.9 1082.2 1084.2 1051.1 1051.4 1056.1 1062.4 1063.8 772.2 773.2 774.0 775.3 776.4 16010.0 16352.8 16408.0 16677.9 16924.7 14956.0 15153.4 15412.0 15449.7 15650.3 11438.7 11738.6 11786.4 11926.4 12235.3 3774.0 3774.5 3779.5 3864.3 3992.8 37582.0 85684.6 110493.4 111318.0 111327.0 11317.4 11461.5 11595.2 12073.3 12300.6 24689.0 25014.6 25187.9 25217.4 25220.0 8225.1 8256.6 8257.5 8315.2 8479.6 4377.8 4466.0 4507.9 4598.8 4698.3 3432.5 3537.0 3543.3 3546.7 3547.4 596.5 617.4 618.2 618.6 632.5 668.5 688.7 690.6 705.9 730.7 9062.4 10344.3 10386.6 10488.2 10620.2 702.5 724.4 725.9 732.8 736.8 4891.2 5030.2 5034.8 5077.4 5128.1 99846.0 103586.5 108177.5 108396.0 110317.4 90348.0 91449.0 92733.1 93116.5 93542.0 68697.7 80531.0 83176.6 83260.0 83393.0 10715.2 10980.8 11240.8 11273.9 11299.9 48387.0 48802.3 49135.0 49299.5 49317.5 46033.5 61751.4 62615.7 63069.0 63136.0 12746.5 12850.8 12873.3 12876.2 13404.5 13576.4 13892.3 13940.5 14525.1 14709.6 10415.9 13135.4 13807.8 14083.9 14090.4 13253.6 13908.9 14027.6 14078.8 14164.4 12726.6 13127.8 13602.4 13613.2 13925.7 9193.3 9747.9 9750.5 9777.4 9841.7 8531.7 9407.7 9780.5 10102.7 10268.1 08:24:33 INFO - PROCESS | 1644 | -------- Summary: end -------- 08:24:33 INFO - PROCESS | 1644 | 08:24:34 INFO - TEST-INFO | 1644: exit 0 08:24:34 INFO - TEST-OK | dromaeo_css | took 1118363ms 08:24:34 INFO - TEST-START | kraken 08:24:34 INFO - operating with platform_type : mac_ 08:24:34 INFO - Initialising browser for kraken test... 08:24:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpnDyB9P/profile http://localhost:49233/getInfo.html) 08:24:36 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 08:24:36 INFO - PROCESS | 1650 | colorDepth:24 08:24:36 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 08:24:36 INFO - PROCESS | 1650 | __metrics 08:24:36 INFO - PROCESS | 1650 | 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" 08:24:36 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:24:37 INFO - TEST-INFO | 1650: exit 0 08:24:37 INFO - Browser initialized. 08:24:37 INFO - Running cycle 1/1 for kraken test... 08:24:37 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpnDyB9P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:49 INFO - PROCESS | 1652 | RSS: Main: 194101248 08:24:49 INFO - PROCESS | 1652 | 08:24:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 08:24:53 INFO - PROCESS | 1652 | RSS: Main: 244658176 08:24:53 INFO - PROCESS | 1652 | 08:24:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 08:24:56 INFO - PROCESS | 1652 | RSS: Main: 259657728 08:24:56 INFO - PROCESS | 1652 | 08:24:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 08:25:00 INFO - PROCESS | 1652 | RSS: Main: 355323904 08:25:00 INFO - PROCESS | 1652 | 08:25:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 08:25:02 INFO - PROCESS | 1652 | RSS: Main: 258023424 08:25:02 INFO - PROCESS | 1652 | 08:25:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:25:05 INFO - PROCESS | 1652 | RSS: Main: 260907008 08:25:05 INFO - PROCESS | 1652 | 08:25:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 08:25:09 INFO - PROCESS | 1652 | RSS: Main: 300011520 08:25:09 INFO - PROCESS | 1652 | 08:25:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 08:25:12 INFO - PROCESS | 1652 | RSS: Main: 300978176 08:25:12 INFO - PROCESS | 1652 | 08:25:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 08:25:16 INFO - PROCESS | 1652 | RSS: Main: 301088768 08:25:16 INFO - PROCESS | 1652 | 08:25:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:25:18 INFO - PROCESS | 1652 | RSS: Main: 276672512 08:25:18 INFO - PROCESS | 1652 | 08:25:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:25:20 INFO - PROCESS | 1652 | RSS: Main: 280739840 08:25:20 INFO - PROCESS | 1652 | 08:25:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:25:23 INFO - PROCESS | 1652 | RSS: Main: 282570752 08:25:23 INFO - PROCESS | 1652 | 08:25:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:25:27 INFO - PROCESS | 1652 | RSS: Main: 297619456 08:25:27 INFO - PROCESS | 1652 | 08:25:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:25:31 INFO - PROCESS | 1652 | RSS: Main: 291561472 08:25:31 INFO - PROCESS | 1652 | 08:25:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 08:25:33 INFO - PROCESS | 1652 | RSS: Main: 336023552 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | __start_tp_report 08:25:33 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:25:33 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:25:33 INFO - PROCESS | 1652 | |i|pagename|runs| 08:25:33 INFO - PROCESS | 1652 | |0;ai-astar;104;117;123;168;162;115;114;116;115;115 08:25:33 INFO - PROCESS | 1652 | |1;audio-beat-detection;131;111;107;140;110;106;105;106;125;107 08:25:33 INFO - PROCESS | 1652 | |2;audio-dft;187;184;183;195;259;207;184;183;184;184 08:25:33 INFO - PROCESS | 1652 | |3;audio-fft;83;60;59;90;64;59;59;59;75;61 08:25:33 INFO - PROCESS | 1652 | |4;audio-oscillator;86;68;85;69;71;68;81;69;96;83 08:25:33 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;86;85;87;86;86;86;86;86;87;86 08:25:33 INFO - PROCESS | 1652 | |6;imaging-darkroom;94;94;94;95;94;96;93;94;94;94 08:25:33 INFO - PROCESS | 1652 | |7;imaging-desaturate;86;84;84;86;86;82;87;83;86;127 08:25:33 INFO - PROCESS | 1652 | |8;json-parse-financial;65;59;60;61;62;63;63;65;64;65 08:25:33 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;55;62;56;64;60;58;58;59;59;59 08:25:33 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;89;80;81;81;81;85;81;91;97;97 08:25:33 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;157;145;153;133;135;144;149;155;188;147 08:25:33 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;164;165;164;166;214;221;193;164;161;163 08:25:33 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;64;66;64;65;65;75;80;80;79 08:25:33 INFO - PROCESS | 1652 | __end_tp_report 08:25:33 INFO - PROCESS | 1652 | __start_cc_report 08:25:33 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2141 08:25:33 INFO - PROCESS | 1652 | __end_cc_report 08:25:33 INFO - PROCESS | 1652 | __startTimestamp1496157933056__endTimestamp 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:25:33 INFO - PROCESS | 1652 | Number of tests: 14 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:124.90 Median:116.50 stddev:21.68 (18.6%) stddev-sans-first:21.63 08:25:33 INFO - PROCESS | 1652 | Values: 104.0 117.0 123.0 168.0 162.0 115.0 114.0 116.0 115.0 115.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:114.80 Median:110.50 stddev:12.52 (11.3%) stddev-sans-first:11.83 08:25:33 INFO - PROCESS | 1652 | Values: 131.0 111.0 107.0 140.0 110.0 106.0 105.0 106.0 125.0 107.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:195.00 Median:185.50 stddev:23.75 (12.8%) stddev-sans-first:25.01 08:25:33 INFO - PROCESS | 1652 | Values: 187.0 184.0 183.0 195.0 259.0 207.0 184.0 183.0 184.0 184.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.90 Median:62.50 stddev:11.54 (18.5%) stddev-sans-first:10.67 08:25:33 INFO - PROCESS | 1652 | Values: 83.0 60.0 59.0 90.0 64.0 59.0 59.0 59.0 75.0 61.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:82.00 stddev:9.89 (12.1%) stddev-sans-first:10.01 08:25:33 INFO - PROCESS | 1652 | Values: 86.0 68.0 85.0 69.0 71.0 68.0 81.0 69.0 96.0 83.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:86.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 08:25:33 INFO - PROCESS | 1652 | Values: 86.0 85.0 87.0 86.0 86.0 86.0 86.0 86.0 87.0 86.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 08:25:33 INFO - PROCESS | 1652 | Values: 94.0 94.0 94.0 95.0 94.0 96.0 93.0 94.0 94.0 94.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:86.00 stddev:13.41 (15.6%) stddev-sans-first:14.18 08:25:33 INFO - PROCESS | 1652 | Values: 86.0 84.0 84.0 86.0 86.0 82.0 87.0 83.0 86.0 127.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.50 stddev:2.16 (3.4%) stddev-sans-first:2.13 08:25:33 INFO - PROCESS | 1652 | Values: 65.0 59.0 60.0 61.0 62.0 63.0 63.0 65.0 64.0 65.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:2.62 (4.4%) stddev-sans-first:2.35 08:25:33 INFO - PROCESS | 1652 | Values: 55.0 62.0 56.0 64.0 60.0 58.0 58.0 59.0 59.0 59.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:87.00 stddev:6.77 (7.8%) stddev-sans-first:7.11 08:25:33 INFO - PROCESS | 1652 | Values: 89.0 80.0 81.0 81.0 81.0 85.0 81.0 91.0 97.0 97.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:151.00 stddev:15.31 (10.1%) stddev-sans-first:16.06 08:25:33 INFO - PROCESS | 1652 | Values: 157.0 145.0 153.0 133.0 135.0 144.0 149.0 155.0 188.0 147.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:165.50 stddev:23.05 (13.9%) stddev-sans-first:23.93 08:25:33 INFO - PROCESS | 1652 | Values: 164.0 165.0 164.0 166.0 214.0 221.0 193.0 164.0 161.0 163.0 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:70.50 stddev:7.14 (10.1%) stddev-sans-first:7.39 08:25:33 INFO - PROCESS | 1652 | Values: 66.0 64.0 66.0 64.0 65.0 65.0 75.0 80.0 80.0 79.0 08:25:33 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:25:33 INFO - PROCESS | 1652 | 08:25:33 INFO - TEST-INFO | 1652: exit 0 08:25:33 INFO - TEST-OK | kraken | took 59268ms 08:25:33 INFO - SUITE-END | took 1177s 08:25:34 INFO - Completed test suite (00:19:38) 08:25:34 INFO - javascript benchmark 08:25:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7712.952688346795, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.3716712408204, 3422.6577342265778, 3427.7429192810546, 3453.1367158210446, 3480.1995541025562, 5950.315713692927, 5963.40365963404, 5964.79123230687, 5969.015112506431, 6005.369436209196, 5926.348101708809, 5973.88052238955, 6004.549658775588, 6006.609570377922, 6010.849728756783, 5325.840224793263, 5363.088274993241, 5382.461753824626, 5426.430224826376, 5483.588730845183, 5269.0, 5273.736313184345, 5290.92063619046, 5434.456554344553, 5461.0716178249595, 5347.946520534783, 5363.463653634644, 5374.247605335257, 5382.192671099347, 5402.6758394496455, 5400.0, 5427.402985671612, 5437.6465529740635, 5439.347278326587, 5439.456054394568, 5264.710440925747, 5329.733513324319, 5385.569154467633, 5395.974764794676, 5458.590605704588, 5750.309962804448, 5783.421657834225, 5791.015527360359, 5802.709864506758, 6003.399660034005, 6192.256929168483, 6225.533085018642, 6247.281562620311, 6320.462760665349, 6348.1430006949395, 6308.716107775115, 6349.619022858643, 6353.745850165988, 6361.2048493938255, 6362.840928976812, 5990.820275391745, 6024.307204671475, 6059.151718759377, 6072.939270607326, 6075.696215189223, 6279.058141278845, 6279.529035322369, 6315.336889626615, 6348.841278968062, 6388.8083357499345, 6355.809325720235, 6365.2361716594305, 6371.5221358397375, 6374.872502550016, 6377.298497165308, 6180.381961803783, 6275.995840665563, 6315.494760419156, 6324.0, 6352.968235158794, 6219.191505104308, 6256.374362563707, 6285.842853928597, 6355.0, 6366.045093236052, 6230.875382492325, 6267.2165979252595, 6274.874502509924, 6310.6213627182515, 6337.7781777637565, 4868.634852386085, 4877.0, 4911.336969509142, 4916.213405855046, 4917.852464426073, 4381.079973205662, 4381.890452738707, 4394.582514661102, 4427.911441771211, 4458.888527786831, 5605.0751625982075, 5613.522850557667, 5688.857778555486, 5791.942080579224, 5805.506531944832, 6277.7175027123885, 6320.494360451153, 6372.872542549216, 6374.07575901501, 6377.23473183221, 5789.768409263583, 5798.246227990334, 5802.129680547867, 5807.0998995155815, 5852.590318677737, 5336.493033161843, 5464.808731694372, 5475.123980163155, 5476.972615136899, 5491.0116179087345, 6275.905861411991, 6325.746970121328, 6377.681115944277, 6423.357664233727, 6430.131932189094, 6343.111964324906, 6351.96824015877, 6352.205974253218, 6352.9047064295, 6359.300476947439, 6445.452136568444, 6463.57986730861, 6479.287278399466, 6494.058361537645, 6499.772507962199, 6194.4115309045565, 6206.875862482815, 6276.403741644536, 6303.810885673437, 6328.9050664240995, 6328.0, 6346.777862774781, 6354.6504942226775, 6354.841128971628, 6387.425131738166, 6318.557700960889, 6325.936740632535, 6334.3665633436285, 6348.682565871781, 6352.110704501466, 6145.156453064153, 6203.503719702323, 6284.3087260402235, 6284.968575157095, 6365.522585806176, 6143.573288551087, 6206.789676013134, 6210.347913469021, 6223.0, 6228.096925945803, 6112.929053818996, 6328.810135695937, 6335.873282534416, 6349.269833969152, 6352.301246862934, 6012.849678757891, 6045.153678485103, 6115.816525504242, 6134.509239260759, 6140.938590614037], "value": 5833.948638209331, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.8716511775557, 809.7085049382222, 829.6971605364042, 886.0474989386407, 900.4687234531625, 28072.0, 28413.57379639307, 29223.0, 40174.19651606966, 40856.59143408565, 41604.16791664172, 41859.16281674365, 41924.0, 41924.79037604816, 42070.7896460518, 9459.763505912373, 9643.90356096437, 9667.854982175273, 9672.790901137358, 9683.176929960962, 7699.345555627779, 7854.764357069268, 9516.38143520669, 9633.903660963406, 11555.017823485014, 11783.175177737561, 11824.290542567473, 11824.645260642152, 11825.645230643051, 11852.0, 11723.47244374001, 11780.293182409083, 11784.292942423483, 11787.115966302561, 11789.528418863236, 11395.316281023164, 11446.542138314457, 11456.942715286454, 11458.0, 11474.770504589862, 39449.0, 39544.40683389751, 39591.40612890809, 39597.80201098976, 39615.80192099021, 3424.1004376182013, 3957.52509698838, 3979.1047014421815, 4018.7789671568053, 4061.878143655695, 27545.44909101827, 27552.311192220153, 27596.310092247855, 29613.259668508243, 29655.0, 3035.620547431571, 3218.4850423932294, 3306.553615261933, 3312.668733126692, 3315.055209265372, 364598.0, 364804.0, 364969.0, 364973.0, 365262.9999999973, 52928.20607690888, 53138.46861531412, 53208.0, 53210.46789532055, 53271.73364133154, 53763.0, 55057.724711376184, 55059.72470137624, 55125.44874551203, 55158.0, 13136.474541018444, 15016.24918754058, 15016.324265407864, 15061.0, 15067.171305578298, 13002.89475394602, 13042.869571304165, 13047.086703930823, 13067.281299528428, 13072.477100915858, 8675.436096653697, 10168.644097456554, 10381.273310868317, 11697.473613687405, 12032.759344813056, 4530.28794749844, 5675.0636145035605, 5711.257536520226, 5718.199452076713, 5753.482186603226, 922.8846394200725, 941.4539567051094, 963.1235575626147, 997.7056768253383, 997.7904640025531, 26958.5956210657, 27756.30609234785, 28225.576616350772, 28307.858460707976, 28315.858420708177, 931.0, 1053.6048981631889, 1091.4542728635681, 1095.6767753512734, 1097.6322931817772, 14117.43530258778, 14433.855661443462, 14454.349554270084, 14752.778708319383, 14757.335919883632, 414465.0, 415513.9224303859, 416627.0, 416915.0, 418728.9063554663, 55710.0, 55771.16343254854, 55905.440945590024, 55906.44093559094, 56372.15441768377, 1254.1722463174444, 1277.897768178525, 1463.9560813175622, 1478.5194811686245, 1485.688005518853, 26771.33071673224, 27986.72013279841, 30123.096307110824, 30377.69622303749, 30600.84699576577, 42428.36357454579, 42599.7870010648, 42606.5739342615, 42632.36051459169, 42633.36049959315, 368320.0, 369039.0, 369387.15306423296, 369826.0, 370519.0, 27344.0, 27801.304967375167, 35769.0, 38970.61029389783, 40414.311491261564, 46761.76619116883, 48289.75855120702, 48377.0, 48977.755111224214, 49705.50294497009, 31932.680673192972, 31936.840315798272, 32042.0, 33143.83996560109, 33496.497552536246, 31887.36225275528, 32178.88065696629, 32655.020349389557, 32741.672583273863, 32974.67025329716, 39341.0, 39896.40155397613, 40169.79915100522, 40270.1945961085, 40300.79849600851, 32367.190820229684, 32640.510392344608, 32691.50962735513, 33640.327193456484, 33775.83112084424, 22240.110395583997, 23775.76224237805, 25466.981320747705, 26042.95828166928, 26733.19800405991], "value": 18945.086275767262, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.37213910977334, 414.90457194845203, 424.17921322241483, 430.8664314062641, 458.9839355622554, 20956.0, 21914.780852191474, 22185.889070554626, 22301.0, 22377.664335034948, 21252.787472125274, 22784.43038924028, 23184.42038949027, 23511.647325290134, 23774.0490380385, 6824.385805277524, 6902.654867256655, 7078.292170782932, 7213.531120477179, 7221.350078492935, 6981.650917454107, 7114.644267786616, 7121.465890058241, 7130.144382674061, 7272.63636818157, 7721.72973945912, 7774.0, 7776.0, 7778.183290754474, 7780.727674531393, 7502.287282708134, 7659.213838829493, 7692.423068269902, 7753.573553454557, 7760.495567788075, 7227.819304517377, 7392.0, 7430.145533263636, 7444.404447644176, 7475.476716629838, 21839.890800546054, 25701.0, 25754.74245257542, 26170.0, 26602.33494162642, 2962.5111856543645, 2980.5231163013814, 3110.7355874750674, 3112.2686168750324, 3131.827749473778, 14217.502387416494, 16497.752533712002, 16589.83410165895, 16676.0, 17142.657146856993, 2063.7420322459693, 2094.979050209494, 2125.171183238538, 2152.53720450104, 2221.3780141560387, 85364.08816428084, 109212.45393773059, 134081.0, 139732.0, 164484.17757911133, 16629.83370166283, 19374.99687548432, 20164.580250369756, 20625.17499300041, 20801.687974680393, 31570.526442103386, 31638.36723265522, 31995.360092798015, 32068.679313207034, 32109.0, 8163.3061189798345, 10063.69808905734, 10293.279470436997, 10413.323133996266, 10665.946670266754, 8661.740147795575, 8677.39258251929, 8685.174908383693, 8821.647134114564, 8828.117188281121, 7744.380449564022, 9386.812263754686, 9442.291828112919, 9542.0, 9551.997040310667, 3321.7508686848582, 3939.5075615548058, 4007.198560287896, 4015.477987861559, 4019.3167161582014, 790.6284046498137, 884.5975081337975, 888.200619442494, 915.8259930613162, 916.3173435790379, 21068.367948961557, 21705.782942170375, 21790.0, 21807.67288490674, 21909.151810264564, 931.417863835103, 933.8785957825575, 952.3048174832411, 955.6273053509137, 973.2846357926918, 10195.694129176136, 10803.351798892092, 10896.455177241107, 11337.696164940973, 11420.543178272943, 742352.0, 753245.0, 753351.0, 755105.0, 768169.0, 144615.83076253865, 144904.0, 147755.0, 149596.2520187392, 150717.0, 1209.5585111434439, 1232.5439587352737, 1246.8254444377794, 1247.9126461147632, 1261.1298204239047, 15587.844121558639, 17203.483895483154, 18488.260469581604, 18511.629767404847, 18921.71617425767, 62012.68993655003, 62178.0, 62987.0, 63952.68023660038, 64224.07327780174, 87468.56265718631, 87601.68597470915, 87784.0, 87900.5604971971, 90750.54624727098, 9198.218151456933, 11505.252158609663, 11574.131940104357, 11626.593069242535, 12024.458899349374, 13065.869341306465, 13099.934500327437, 13125.934370328087, 13324.267165306, 13379.732405351644, 11189.832152517556, 11376.146788990693, 11449.828252576384, 11538.0, 11574.594889179176, 10364.948175259075, 10496.317739346918, 10668.03987641116, 10758.838617420588, 10764.461776910966, 11421.37182454973, 11651.126165537788, 11747.588834390755, 11826.113041521749, 11850.288982661068, 10308.484575771332, 11417.858214178515, 11421.942890285827, 11518.251313664585, 11611.593594224161, 9302.720918372459, 10732.946335268274, 11270.211085223955, 11516.82724759146, 11592.88407115918], "value": 11599.870346543536, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.2360278244178, 1445.5229774174522, 1599.0086146589117, 1627.8697704183667, 1643.3590899549179, 9277.536123193839, 9713.32006759527, 10878.347299162057, 11129.833052504198, 11212.831807522894, 11029.724256893562, 11115.88841115888, 11131.220814542965, 11131.276467029638, 11178.888211117886, 2326.0463210083913, 2370.9051637934463, 2371.1819422299277, 2371.490129622131, 2373.774491423312, 1967.9606407871834, 2060.53637931465, 2313.9537209255805, 2388.474535602165, 2399.7360290368133, 2340.7425183229916, 2353.482233908537, 2354.117206047732, 2360.5868972929757, 2362.3267368799898, 2303.216906251874, 2326.325365643961, 2332.5568142053125, 2336.439254578906, 2337.403961989699, 2290.267114523353, 2303.976960230393, 2306.4810417656063, 2307.204014614955, 2321.373229228099, 11225.32648041112, 11287.717807054807, 11309.830352544635, 11315.264507806965, 11315.603953861657, 5516.0, 5716.28546431696, 5716.771329146829, 5722.941255867678, 5727.398623144593, 3118.485449900764, 3243.254051568129, 3490.89527314181, 3497.45789402643, 3506.7895926244505, 1070.5664205996584, 1124.454639499842, 1164.5865717670274, 1172.9648110556698, 1187.6258978421856, 14841.183734894577, 15237.542873713806, 15686.450974215848, 15773.842261577467, 15797.526074217792, 4453.1316393303, 4547.590716835501, 4574.6340292776495, 4581.74800385982, 4591.586757191869, 10351.378917264989, 11545.365004924643, 11774.0, 11793.351365674971, 11794.233374830608, 5612.97193514038, 5613.887722245614, 5614.29821272341, 5623.746931388097, 5625.606207565513, 4344.56554344563, 5044.798208071636, 5091.0, 5145.871353216125, 5218.321618189611, 3367.5285460035616, 3453.4129198036394, 3705.740598158157, 3772.86794962175, 3790.166163444038, 947.4173383369179, 950.2635457520339, 960.4573416019927, 976.1848856205006, 976.584951395654, 821.8561751693429, 882.2016075451727, 898.0884402331508, 900.8333458310234, 918.2653876898454, 6631.86736265272, 6670.866582668319, 6679.532432729759, 6851.828704282333, 6880.415164711056, 919.4069824962995, 928.8513837785922, 1040.4173662748885, 1052.4842827014709, 1081.2107161771794, 4170.145120250341, 4199.307114326163, 4264.552222016706, 4344.261475549164, 4390.560943905584, 13404.329783510786, 13951.02342836012, 13956.860431395557, 13970.580882573538, 14013.369398377097, 13141.737165256642, 13532.120412173177, 13898.652533686738, 13908.791368129487, 13988.020838541408, 10064.194864410976, 10323.793524129625, 10357.119644830274, 10379.740506487247, 10383.584656613803, 5920.674362910081, 6502.642354670538, 6658.201015878126, 6725.630090345078, 6932.202796678245, 11929.105317101426, 12218.999999999643, 12361.258324500559, 12365.010799135867, 12378.690532736393, 12232.449539770554, 12446.688832779253, 12647.936760316139, 12675.93662031684, 12692.0, 9108.0, 9284.442933424016, 9285.535723213678, 9288.628454862, 9985.45080020606, 10484.42335671515, 10562.471876406302, 10801.297915635772, 10823.621173258898, 10835.404052777172, 8627.35294852876, 8730.214280714767, 8823.64705411802, 8850.291976641724, 8969.609710494884, 8622.137786221328, 8649.8702519461, 8709.99505170715, 8734.868976965477, 8741.868871967054, 7973.521588704696, 8128.593570321342, 8128.715494957648, 8139.511629302261, 8141.959290203511, 5802.1586869902785, 5849.590528662953, 5862.677552734641, 5877.559183061373, 6102.359252278447, 6029.336772955059, 6033.78881739137, 6203.658798766111, 6240.157578726814, 6359.395857393541], "value": 5082.820149084702, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.37853163748184, 381.7938313310812, 384.2296196126765, 390.7011136480591, 392.1177350960338, 4370.562943705628, 4372.519022907478, 4891.923776769105, 4971.154903666376, 5138.537531622158, 3989.840406383741, 4418.293073108295, 4753.500882407349, 5075.857931965306, 5079.492050794928, 3805.6955443564516, 4221.620054195122, 4319.913601727963, 4325.567443255665, 4329.393884856123, 3877.983448606737, 3961.8217180226816, 4304.332828411586, 4335.937695264664, 4362.214801335758, 4156.334986402177, 4295.591918767712, 4343.63079138272, 4344.239758042346, 4356.607905288523, 3693.8891833245048, 4251.97874010631, 4294.699371044028, 4298.441202643668, 4314.697971142022, 3932.8426862925735, 3950.8147555733167, 3951.905490090257, 3966.960330396717, 4069.7151199416053, 4149.97925010373, 5129.8717532062, 5141.305923700327, 5142.640015198937, 5181.119209734355, 4620.514845941195, 4635.804596839866, 4695.319178719101, 4912.656114072016, 4999.000199959986, 4670.509596492353, 4795.211782691669, 4936.569773249433, 5270.57835373173, 5521.0, 4168.228877657643, 4415.933760993587, 4429.6744209290055, 4430.335449682573, 4451.28471632304, 4952.182889823175, 5546.69493177875, 5620.213170156181, 5768.913466298009, 5912.82261532159, 3759.3045286621514, 4157.376393540993, 4494.550544945545, 4579.351433483943, 4590.84230411706, 3860.478835357247, 4045.9797701011307, 4077.4903137107863, 4093.891645802414, 4141.772202528829, 4138.482689663792, 4197.622214000755, 4469.7318160910445, 4536.280445985915, 4546.499885012646, 5488.835334939959, 5492.450754924475, 5508.14623733322, 5538.501534861882, 5544.91682624761, 1419.6876687128815, 1423.665438621923, 1553.4562902983864, 1579.7125342845545, 1582.8603405547988, 1069.79139067881, 1087.597588892115, 1088.3361149698678, 1176.8117101263756, 1204.27141579345, 4612.97693511537, 4773.928391074137, 4920.620647623547, 4933.777979990909, 4993.875153121128, 5492.423295553909, 5677.460641239159, 5689.065717656017, 5705.857353566194, 5768.0, 4491.92193873468, 4642.313166371168, 4911.607071434277, 4912.778924948386, 4958.776855041532, 2949.2626843289177, 2952.926176845553, 2954.5568164775455, 2955.704429557027, 2955.9556606650917, 5147.525420341255, 5367.194920761917, 5495.780168793283, 5877.882442351129, 5899.941000590025, 5089.447718445836, 5130.0, 5447.214186140594, 5525.364583072917, 5834.0, 5262.184731168959, 5344.5724342052545, 5629.7185140742795, 5713.857153571194, 5926.555508336805, 4784.593309568726, 5189.870253243699, 5651.8304450866535, 5657.32112146545, 5676.0, 5295.973520132375, 5371.62398632092, 5570.6657600544095, 5643.407442218672, 5646.205128461438, 4985.177445721415, 5590.211132437581, 5892.5285977122585, 5928.347881733092, 5937.101890939905, 3961.2372494239785, 3993.740406873544, 4409.867703968886, 4421.0, 4505.008898042425, 4832.855014349575, 5349.545288650508, 5622.8313150605545, 5663.440930051183, 5735.971320143372, 4868.442098528437, 5354.83129332621, 5396.109641909041, 5405.135178371442, 5542.8914217155925, 4681.485036646034, 5130.948690513047, 5483.341998960151, 5526.889462210653, 5538.529225015919, 4590.495045545054, 5339.279197308314, 5362.70505122222, 5367.651102678314, 5421.647592906448, 4950.356453661001, 5282.973585132204, 5347.101778868446, 5531.253280807039, 5545.916811247916, 5030.723310217973, 5332.840014799563, 5337.653052551572, 5346.278252435872, 5388.919166212589], "value": 4180.338195088593, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.945764338853, 738.1437532462345, 764.2357642357642, 768.8500742355243, 893.1470445724332, 13703.58889233322, 15493.302801373937, 16308.510744677678, 16336.93809902357, 16339.591510212236, 12963.416646250918, 12999.675008124801, 13014.674633134176, 13066.281354525498, 13146.276954767483, 975.2977855943727, 977.8484334928097, 994.721477986165, 998.101708462386, 1011.5043628621996, 982.4056445850265, 1000.3797645459825, 1000.8048430556045, 1001.8246806808816, 1002.8395456726926, 1036.6734478639241, 1079.5357996061691, 1081.8918108189198, 1082.161324973148, 1084.230196560439, 1051.1223128687554, 1051.3691784929058, 1056.060106505211, 1062.3572738493178, 1063.7766069125494, 772.231629528617, 773.207462351089, 773.957432341221, 775.3061010395664, 776.4176867349488, 16010.0, 16352.75470867938, 16408.0, 16677.91661041687, 16924.66150676992, 14956.0, 15153.393864245418, 15412.0, 15449.691006179813, 15650.295736691876, 11438.714032149179, 11738.589149379815, 11786.35175065371, 11926.403679815974, 12235.265884046985, 3773.9622603773887, 3774.5281839770028, 3779.4897688812207, 3864.3430616795213, 3992.800359982018, 37582.00308611331, 85684.57157714234, 110493.44753276263, 111317.99999999919, 111327.0, 11317.434128293553, 11461.480081548825, 11595.18833681651, 12073.33596652175, 12300.569480068154, 24689.0, 25014.624780628306, 25187.874060629947, 25217.369565760637, 25220.0, 8225.136360682161, 8256.628451719687, 8257.504549727035, 8315.168483151636, 8479.576021198915, 4377.846775362847, 4466.017476155241, 4507.864764057083, 4598.839040633562, 4698.342232087511, 3432.5366075579977, 3536.9823150884595, 3543.344481270973, 3546.716262698978, 3547.4324108142573, 596.5394774591167, 617.3857012272817, 618.2333905956582, 618.5855476830534, 632.5256057956533, 668.5053060735021, 688.703857341348, 690.5856486108295, 705.8905869590221, 730.7150211417552, 9062.365634405529, 10344.277813761119, 10386.584536618451, 10488.206253428476, 10620.198539014942, 702.5117543307352, 724.3661795928563, 725.9274072592804, 732.783828770514, 736.7900148457663, 4891.217405215149, 5030.245463180479, 5034.810958876887, 5077.365329333833, 5128.076946149728, 99846.0, 103586.48206758918, 108177.45911270393, 108396.0, 110317.44841275743, 90348.0, 91449.0, 92733.0726692738, 93116.53441732883, 93542.0, 68697.65651171912, 80531.0, 83176.58411707902, 83260.0, 83393.0, 10715.249932504961, 10980.780384392427, 11240.775184496428, 11273.887261127284, 11299.887001129884, 48387.0, 48802.26796597983, 49135.0, 49299.45546325296, 49317.50682493272, 46033.539664602926, 61751.38248617456, 62615.747685046954, 63069.0, 63136.0, 12746.490140394651, 12850.807237891626, 12873.291968941423, 12876.163049401759, 13404.530841420503, 13576.389062492013, 13892.30538473052, 13940.512082077079, 14525.128492290496, 14709.632259193606, 10415.947920260653, 13135.408906599418, 13807.79288310656, 14083.859161408665, 14090.436382544583, 13253.602391928258, 13908.86091139116, 14027.649308767363, 14078.788818167941, 14164.433422662978, 12726.618201453972, 13127.803082953955, 13602.38789254466, 13613.183209007491, 13925.721485570435, 9193.264538837011, 9747.9025209747, 9750.512474376395, 9777.364471309342, 9841.703029484916, 8531.677589973564, 9407.717768466957, 9780.462074585967, 10102.69691909244, 10268.127209187003], "value": 7727.749483510398, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1455.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 123.0, 168.0, 162.0, 115.0, 114.0, 116.0, 115.0, 115.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 111.0, 107.0, 140.0, 110.0, 106.0, 105.0, 106.0, 125.0, 107.0], "value": 114.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 183.0, 195.0, 259.0, 207.0, 184.0, 183.0, 184.0, 184.0], "value": 195.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 59.0, 90.0, 64.0, 59.0, 59.0, 59.0, 75.0, 61.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 85.0, 69.0, 71.0, 68.0, 81.0, 69.0, 96.0, 83.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 86.0, 86.0, 86.0, 86.0, 86.0, 87.0, 86.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 96.0, 93.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 86.0, 86.0, 82.0, 87.0, 83.0, 86.0, 127.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 59.0, 60.0, 61.0, 62.0, 63.0, 63.0, 65.0, 64.0, 65.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 56.0, 64.0, 60.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 80.0, 81.0, 81.0, 81.0, 85.0, 81.0, 91.0, 97.0, 97.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 145.0, 153.0, 133.0, 135.0, 144.0, 149.0, 155.0, 188.0, 147.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 164.0, 166.0, 214.0, 221.0, 193.0, 164.0, 161.0, 163.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 64.0, 65.0, 65.0, 75.0, 80.0, 80.0, 79.0], "value": 70.4, "unit": "score"}]}]} 08:25:34 INFO - Return code: 0 08:25:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:25:34 INFO - # TBPL SUCCESS # 08:25:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:34 INFO - 2017-05-30 08:25:34.346 screenresolution[1656:10904] starting screenresolution argv=screenresolution get 08:25:34 INFO - 2017-05-30 08:25:34.348 screenresolution[1656:10904] Display 0: 1600x1200x32@60 08:25:34 INFO - 2017-05-30 08:25:34.354 screenresolution[1657:10907] starting screenresolution argv=screenresolution list 08:25:34 INFO - Available Modes on Display 0 08:25:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:34 INFO - Intel Iris: 08:25:34 INFO - Chipset Model: Intel Iris 08:25:34 INFO - Type: GPU 08:25:34 INFO - Bus: Built-In 08:25:34 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:34 INFO - Vendor: Intel (0x8086) 08:25:34 INFO - Device ID: 0x0a2e 08:25:34 INFO - Revision ID: 0x0009 08:25:34 INFO - Displays: 08:25:34 INFO - Display: 08:25:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:34 INFO - Display Serial Number: 1600x1200 60 08:25:34 INFO - Main Display: Yes 08:25:34 INFO - Mirror: Off 08:25:34 INFO - Online: Yes 08:25:34 INFO - Rotation: Supported 08:25:34 INFO - Return code: 0 08:25:34 INFO - Running post-action listener: _resource_record_post_action 08:25:34 INFO - [mozharness: 2017-05-30 15:25:34.590685Z] Finished run-tests step (success) 08:25:34 INFO - Running post-run listener: _resource_record_post_run 08:25:34 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 33172480; Write bytes: 511282688; Read time: 200; Write time: 1562 08:25:34 INFO - TinderboxPrint: CPU usage
54.1% 08:25:34 INFO - TinderboxPrint: I/O read bytes / time
33,172,480 / 200 08:25:34 INFO - TinderboxPrint: I/O write bytes / time
511,282,688 / 1,562 08:25:34 INFO - TinderboxPrint: CPU idle
2,204.1 (45.9%) 08:25:34 INFO - TinderboxPrint: CPU system
958.3 (20.0%) 08:25:34 INFO - TinderboxPrint: CPU user
1,635.2 (34.1%) 08:25:34 INFO - TinderboxPrint: Swap in / out
238,514,176 / 0 08:25:34 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201054720; Write bytes: 199704576; Read time: 15539; Write time: 422 08:25:34 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32250880; Write bytes: 301649408; Read time: 188; Write time: 1110 08:25:35 INFO - Running post-run listener: _upload_blobber_files 08:25:35 INFO - Blob upload gear active. 08:25:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:25:35 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 08:25:35 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'] 08:25:35 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 08:25:35 INFO - (blobuploader) - INFO - Open directory for files ... 08:25:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:25:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:25:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:36 INFO - (blobuploader) - INFO - Done attempting. 08:25:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:25:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:25:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:37 INFO - (blobuploader) - INFO - Done attempting. 08:25:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:38 INFO - (blobuploader) - INFO - Done attempting. 08:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:40 INFO - (blobuploader) - INFO - Done attempting. 08:25:40 INFO - (blobuploader) - INFO - Iteration through files over. 08:25:40 INFO - Return code: 0 08:25:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:25:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:25:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/582cf8984fedecf0a5ce520d6ed1b9c433b5e97d0e51a024209886c154c92ed13b0abee50e1d46c56c3722911e3dcf2d86e8299de8f1f1ac44a1d88f65830fdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b9469d717624788554f56bd98d8864ca3f3bb6cfc08633d7f45230011d834afb8b85cdfc50e06c957fc4b163c078d39425a9f9ac2fe8c6837f8c22441ca0bca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/847854ad76ab073a257850be733699980b9657f017d5dd04e33999c657fb4964988cc1fbba1ecaead1d2541e5c24f4a40c9f3af906cb267e4eb8c189abcfb016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702b47a249ad951c70a5cf94e1ad026e87a5eb795470a93455d12224c0981899765c21b9dc347598e93c51601d850396b66e2d09fe8db08e7b8ab93365cc1eac"} 08:25:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:25:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:25:40 INFO - Contents: 08:25:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/582cf8984fedecf0a5ce520d6ed1b9c433b5e97d0e51a024209886c154c92ed13b0abee50e1d46c56c3722911e3dcf2d86e8299de8f1f1ac44a1d88f65830fdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b9469d717624788554f56bd98d8864ca3f3bb6cfc08633d7f45230011d834afb8b85cdfc50e06c957fc4b163c078d39425a9f9ac2fe8c6837f8c22441ca0bca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/847854ad76ab073a257850be733699980b9657f017d5dd04e33999c657fb4964988cc1fbba1ecaead1d2541e5c24f4a40c9f3af906cb267e4eb8c189abcfb016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702b47a249ad951c70a5cf94e1ad026e87a5eb795470a93455d12224c0981899765c21b9dc347598e93c51601d850396b66e2d09fe8db08e7b8ab93365cc1eac"} 08:25:40 INFO - Running post-run listener: copy_logs_to_upload_dir 08:25:40 INFO - Copying logs to upload dir... 08:25:40 INFO - mkdir: /builds/slave/test/build/upload/logs 08:25:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.037364 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-30 08:25:40.803290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:40.807376) ========= 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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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/582cf8984fedecf0a5ce520d6ed1b9c433b5e97d0e51a024209886c154c92ed13b0abee50e1d46c56c3722911e3dcf2d86e8299de8f1f1ac44a1d88f65830fdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b9469d717624788554f56bd98d8864ca3f3bb6cfc08633d7f45230011d834afb8b85cdfc50e06c957fc4b163c078d39425a9f9ac2fe8c6837f8c22441ca0bca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/847854ad76ab073a257850be733699980b9657f017d5dd04e33999c657fb4964988cc1fbba1ecaead1d2541e5c24f4a40c9f3af906cb267e4eb8c189abcfb016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702b47a249ad951c70a5cf94e1ad026e87a5eb795470a93455d12224c0981899765c21b9dc347598e93c51601d850396b66e2d09fe8db08e7b8ab93365cc1eac"} build_url:https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011063 build_url: 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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/582cf8984fedecf0a5ce520d6ed1b9c433b5e97d0e51a024209886c154c92ed13b0abee50e1d46c56c3722911e3dcf2d86e8299de8f1f1ac44a1d88f65830fdb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b9469d717624788554f56bd98d8864ca3f3bb6cfc08633d7f45230011d834afb8b85cdfc50e06c957fc4b163c078d39425a9f9ac2fe8c6837f8c22441ca0bca", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/847854ad76ab073a257850be733699980b9657f017d5dd04e33999c657fb4964988cc1fbba1ecaead1d2541e5c24f4a40c9f3af906cb267e4eb8c189abcfb016", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702b47a249ad951c70a5cf94e1ad026e87a5eb795470a93455d12224c0981899765c21b9dc347598e93c51601d850396b66e2d09fe8db08e7b8ab93365cc1eac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:40.840116) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:40.840444) ========= 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.zGZ5cT5cz5/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.AIw9TvGEl1/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005288 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:40.894728) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 08:25:40.895074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 08:25:40.895397) ========= ========= Total master_lag: 0.21 =========